__step=000700
__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 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000700.txt
__path_data=data/data000700.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6987292.129443301
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=50
DEL_FRAC=50
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=50.0 50.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


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.022s
prefilling_size=3831166 prefilling_elapsed_millis=1.001s
prefilling_size=4122431 prefilling_elapsed_millis=1.101s
prefilling_size=4398789 prefilling_elapsed_millis=1.201s
prefilling_size=4663658 prefilling_elapsed_millis=1.302s
prefilling_size=4915122 prefilling_elapsed_millis=1.402s
prefilling_size=5155598 prefilling_elapsed_millis=1.502s
prefilling_size=5383722 prefilling_elapsed_millis=1.602s
prefilling_size=5599112 prefilling_elapsed_millis=1.702s
prefilling_size=5804895 prefilling_elapsed_millis=1.803s
prefilling_size=6000945 prefilling_elapsed_millis=1.903s
prefilling_size=6187914 prefilling_elapsed_millis=2.003s
prefilling_size=6366013 prefilling_elapsed_millis=2.103s
prefilling_size=6536481 prefilling_elapsed_millis=2.203s
prefilling_size=6697682 prefilling_elapsed_millis=2.304s
prefilling_size=6852612 prefilling_elapsed_millis=2.404s
prefilling_size=6998294 prefilling_elapsed_millis=2.504s
prefilling_size=7140074 prefilling_elapsed_millis=2.604s
prefilling_size=7273804 prefilling_elapsed_millis=2.704s
prefilling_size=7401243 prefilling_elapsed_millis=2.805s
prefilling_size=7522484 prefilling_elapsed_millis=2.905s
prefilling_size=7638212 prefilling_elapsed_millis=3.005s
prefilling_size=7748438 prefilling_elapsed_millis=3.105s
prefilling_size=7854442 prefilling_elapsed_millis=3.205s
prefilling_size=7955353 prefilling_elapsed_millis=3.306s
prefilling_size=8048659 prefilling_elapsed_millis=3.406s
prefilling_size=8141434 prefilling_elapsed_millis=3.506s
prefilling_size=8228911 prefilling_elapsed_millis=3.606s
prefilling_size=8312710 prefilling_elapsed_millis=3.706s
prefilling_size=8390898 prefilling_elapsed_millis=3.807s
prefilling_size=8465304 prefilling_elapsed_millis=3.907s
prefilling_size=8535826 prefilling_elapsed_millis=4.007s
prefilling_size=8605804 prefilling_elapsed_millis=4.107s
prefilling_size=8670406 prefilling_elapsed_millis=4.207s
prefilling_size=8732222 prefilling_elapsed_millis=4.308s
prefilling_size=8792129 prefilling_elapsed_millis=4.408s
prefilling_size=8849222 prefilling_elapsed_millis=4.508s
prefilling_size=8903419 prefilling_elapsed_millis=4.608s
prefilling_size=8954589 prefilling_elapsed_millis=4.708s
prefilling_size=9002909 prefilling_elapsed_millis=4.809s
prefilling_size=9049356 prefilling_elapsed_millis=4.909s
prefilling_size=9093856 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 906128 keys with 96 threads...
tid=0 thread_prefill_amount=97901 percent_done_estimate=1037.2 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=99984 percent_done_estimate=1059.3 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=102067 percent_done_estimate=1081.4 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=104150 percent_done_estimate=1103.4 elapsed_s=5 est_remaining_s=-11 / -5
timing_elapsed 0.102745s
prefilling_size=10000000 prefilling_elapsed_millis=0.103s
finished prefilling to size 10000000 for expected size 10000000 keysum=100008069480830, performing 49713571 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=6987297.548158189
REALTIME_END_PERF_FORMAT=6987307.548282094

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=9999096
tree_stats_avgDegreeAtDepth=9.9991e+06

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=9.9991e+06
tree_stats_avgDegree=9.9991e+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=494715 496087 494582 499299 497403 490254 497266 497513 495674 495981 492840 498866 493067 495305 492384 499526 493390 494056 497384 494277 494370 493739 498391 494405 494535 499579 496350 499592 497490 495282 498105 492502 499172 501925 491908 496722 495076 497016 499067 493601 494387 495622 497984 494594 495369 493240 497994 492792 493097 489212 494024 487124 489567 481616 495406 491397 494171 489387 490366 489171 493319 492410 490501 488586 487392 490400 486533 490201 491144 491283 489342 492514 489580 493144 487939 488601 494370 481784 492217 491604 493344 488342 489281 489428 492575 490870 489147 486666 488254 487756 486198 487069 488580 488222 489584 495561
sum_num_inserts_total=47318957
sum_num_deletes_by_thread=494465 494082 494536 499303 498106 489101 497875 498051 494610 495234 493354 498692 494609 495092 495398 498891 492008 494967 499036 493909 494174 493824 497165 491914 496060 497480 496836 499874 498004 494314 497681 494282 499432 500826 493113 496039 494965 494627 498814 494367 492257 495233 497744 494531 495179 494082 497742 493027 492267 489823 496493 488579 487973 481753 494242 492875 492963 488821 488596 488781 493904 490696 490281 489892 486983 489904 487725 490199 492214 491086 489609 491084 489116 491586 488647 487841 494716 482073 493245 491021 492538 488572 488402 488187 492595 493585 487668 486661 488087 488043 488138 486570 489193 488170 491875 494808
sum_num_deletes_total=47312985
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=989180 990169 989118 998602 995509 979355 995141 995564 990284 991215 986194 997558 987676 990397 987782 998417 985398 989023 996420 988186 988544 987563 995556 986319 990595 997059 993186 999466 995494 989596 995786 986784 998604 1002751 985021 992761 990041 991643 997881 987968 986644 990855 995728 989125 990548 987322 995736 985819 985364 979035 990517 975703 977540 963369 989648 984272 987134 978208 978962 977952 987223 983106 980782 978478 974375 980304 974258 980400 983358 982369 978951 983598 978696 984730 976586 976442 989086 963857 985462 982625 985882 976914 977683 977615 985170 984455 976815 973327 976341 975799 974336 973639 977773 976392 981459 990369
average_num_operations_total=985749
stdev_num_operations_total=811
sum_num_operations_total=94631942
min_num_operations_total=963369
max_num_operations_total=1002751
first_time_thread_terminate_by_thread=10000182 10000128 10000128 10000128 10000127 10000126 10000127 10000126 10000124 10000126 10000127 10000127 10000129 10000127 10000126 10000128 10000129 10000127 10000126 10000128 10000128 10000124 10000125 10000123 10000124 10000129 10000128 10000128 10000124 10000126 10000127 10000126 10000124 10000126 10000127 10000127 10000129 10000127 10000123 10000123 10000129 10000126 10000126 10000128 10000128 10000124 10000127 10000123 10000124 10000128 10000124 10000123 10000127 10000123 10000128 10000126 10000128 10000123 10000124 10000133 10000141 10000124 10000123 10000126 10000123 10000125 10000127 10000123 10000128 10000128 10000123 10000126 10000124 10000124 10000124 10000123 10000127 10000126 10000128 10000128 10000128 10000123 10000125 10000127 10000127 10000124 10000127 10000123 10000123 10000125 10000126 10000123 10000126 10000128 10000125 10000126
min_time_thread_terminate_total=10000123
max_time_thread_terminate_total=10000182
first_time_thread_start_by_thread=8 4 4 4 4 4 4 4 3 4 4 4 4 4 4 3 4 4 4 4 4 4 3 4 3 4 4 4 4 4 4 4 3 4 4 4 4 4 4 3 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=99991896964176
threads_final_size=9999096
final_keysum=99991896964176
final_size=9999096
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=47318957
total_deletes=47312985
total_updates=94631942
total_queries=0
total_ops=94631942
find_throughput=0
rq_throughput=0
update_throughput=9463194
query_throughput=0
total_throughput=9463194


total find                    : 0
total rq                      : 0
total inserts                 : 47318957
total deletes                 : 47312985
total updates                 : 94631942
total queries                 : 0
total ops                     : 94631942
find throughput               : 0
rq throughput                 : 0
update throughput             : 9463194
query throughput              : 0
total throughput              : 9463194

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=56.0208
PAPI_L3_TCM=30.0775
PAPI_TOT_CYC=28204.3
PAPI_TOT_INS=1279.03
garbage=0
total_execution_walltime=15.732s
REALTIME_MAIN_END_PERF_FORMAT=6987307.862459706
[time_cmd_output] time_elapsed_sec=15.91, faults_major=0, faults_minor=331488, mem_maxresident_kb=1293080, user_cputime=1462.76, sys_cputime=6.20, percent_cpu=9227%
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