__step=000697
__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 48 -nprefill 48 -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=data000697.txt
__path_data=data/data000697.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6987244.340582775
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=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
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


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.005s
prefilling_size=5107859 prefilling_elapsed_millis=1s
prefilling_size=5435499 prefilling_elapsed_millis=1.101s
prefilling_size=5741724 prefilling_elapsed_millis=1.201s
prefilling_size=6026483 prefilling_elapsed_millis=1.301s
prefilling_size=6290347 prefilling_elapsed_millis=1.401s
prefilling_size=6535994 prefilling_elapsed_millis=1.501s
prefilling_size=6764756 prefilling_elapsed_millis=1.602s
prefilling_size=6977673 prefilling_elapsed_millis=1.702s
prefilling_size=7177075 prefilling_elapsed_millis=1.802s
prefilling_size=7361688 prefilling_elapsed_millis=1.902s
prefilling_size=7533134 prefilling_elapsed_millis=2.002s
prefilling_size=7693268 prefilling_elapsed_millis=2.103s
prefilling_size=7842487 prefilling_elapsed_millis=2.203s
prefilling_size=7982282 prefilling_elapsed_millis=2.303s
prefilling_size=8112622 prefilling_elapsed_millis=2.403s
prefilling_size=8234602 prefilling_elapsed_millis=2.503s
prefilling_size=8348304 prefilling_elapsed_millis=2.603s
prefilling_size=8454964 prefilling_elapsed_millis=2.704s
prefilling_size=8554334 prefilling_elapsed_millis=2.804s
prefilling_size=8645962 prefilling_elapsed_millis=2.904s
prefilling_size=8731975 prefilling_elapsed_millis=3.004s
prefilling_size=8812540 prefilling_elapsed_millis=3.104s
prefilling_size=8888498 prefilling_elapsed_millis=3.204s
prefilling_size=8958487 prefilling_elapsed_millis=3.305s
prefilling_size=9025880 prefilling_elapsed_millis=3.405s
prefilling_size=9087918 prefilling_elapsed_millis=3.505s
prefilling_size=9145581 prefilling_elapsed_millis=3.605s
prefilling_size=9198909 prefilling_elapsed_millis=3.705s
prefilling_size=9247750 prefilling_elapsed_millis=3.805s
prefilling_size=9295694 prefilling_elapsed_millis=3.906s
prefilling_size=9341159 prefilling_elapsed_millis=4.006s
prefilling_size=9382628 prefilling_elapsed_millis=4.106s
prefilling_size=9420415 prefilling_elapsed_millis=4.206s
prefilling_size=9456238 prefilling_elapsed_millis=4.306s
prefilling_size=9491247 prefilling_elapsed_millis=4.407s
prefilling_size=9523296 prefilling_elapsed_millis=4.507s
prefilling_size=9553288 prefilling_elapsed_millis=4.607s
prefilling_size=9582667 prefilling_elapsed_millis=4.707s
prefilling_size=9608116 prefilling_elapsed_millis=4.807s
prefilling_size=9631308 prefilling_elapsed_millis=4.908s
prefilling_size=9655880 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 344089 keys with 48 threads...
tid=0 thread_prefill_amount=237462 percent_done_estimate=3312.6 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=241628 percent_done_estimate=3370.7 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0429925s
prefilling_size=10000000 prefilling_elapsed_millis=0.043s
finished prefilling to size 10000000 for expected size 10000000 keysum=99994216292039, performing 71090302 updates; total_prefilling_elapsed_ms=43 ms)
pref_size=10000000
pref_millis=43
prefill_elapsed_ms=43
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6987249.660160010
REALTIME_END_PERF_FORMAT=6987259.660240475

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=9997639
tree_stats_avgDegreeAtDepth=9.99764e+06

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=9.99764e+06
tree_stats_avgDegree=9.99764e+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=1430727 1439116 1441728 1439669 1440120 1438120 1443356 1447684 1455924 1449471 1455018 1436386 1455592 1442477 1440052 1443619 1451468 1449166 1439100 1439182 1442703 1451519 1442963 1434672 1439225 1440982 1441724 1442474 1442680 1437149 1442700 1444321 1451702 1451550 1450076 1435701 1452523 1441828 1437316 1445021 1453001 1446981 1437756 1436666 1443817 1455636 1443955 1435873
sum_num_inserts_total=69300489
sum_num_deletes_by_thread=1431908 1438691 1442464 1440720 1438338 1440096 1442451 1447546 1456330 1447050 1455460 1434325 1454850 1443712 1438797 1444471 1450811 1446965 1439053 1437398 1441505 1452020 1442780 1434417 1438967 1440541 1441098 1442289 1440964 1437331 1442338 1443910 1452348 1449102 1450119 1436166 1453398 1441915 1437973 1442208 1451484 1446390 1438500 1438389 1446753 1454769 1442235 1435166
sum_num_deletes_total=69286511
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2862635 2877807 2884192 2880389 2878458 2878216 2885807 2895230 2912254 2896521 2910478 2870711 2910442 2886189 2878849 2888090 2902279 2896131 2878153 2876580 2884208 2903539 2885743 2869089 2878192 2881523 2882822 2884763 2883644 2874480 2885038 2888231 2904050 2900652 2900195 2871867 2905921 2883743 2875289 2887229 2904485 2893371 2876256 2875055 2890570 2910405 2886190 2871039
average_num_operations_total=2887229
stdev_num_operations_total=1821
sum_num_operations_total=138587000
min_num_operations_total=2862635
max_num_operations_total=2912254
first_time_thread_terminate_by_thread=10000135 10000082 10000081 10000081 10000080 10000081 10000081 10000080 10000080 10000080 10000080 10000080 10000080 10000080 10000081 10000082 10000081 10000080 10000081 10000081 10000081 10000080 10000082 10000081 10000081 10000081 10000081 10000081 10000080 10000080 10000082 10000080 10000080 10000080 10000082 10000080 10000081 10000081 10000080 10000080 10000080 10000081 10000081 10000081 10000081 10000080 10000080 10000080
min_time_thread_terminate_total=10000080
max_time_thread_terminate_total=10000135
first_time_thread_start_by_thread=8 4 5 4 5 4 4 5 4 4 5 4 4 4 5 5 5 5 4 4 5 5 5 4 3 4 5 4 5 4 4 5 4 4 5 4 4 4 5 5 5 5 4 4 5 5 5 4
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=99991319660964
threads_final_size=9997639
final_keysum=99991319660964
final_size=9997639
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=69300489
total_deletes=69286511
total_updates=138587000
total_queries=0
total_ops=138587000
find_throughput=0
rq_throughput=0
update_throughput=13858700
query_throughput=0
total_throughput=13858700


total find                    : 0
total rq                      : 0
total inserts                 : 69300489
total deletes                 : 69286511
total updates                 : 138587000
total queries                 : 0
total ops                     : 138587000
find throughput               : 0
rq throughput                 : 0
update throughput             : 13858700
query throughput              : 0
total throughput              : 13858700

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=54.4703
PAPI_L3_TCM=25.6105
PAPI_TOT_CYC=9641.46
PAPI_TOT_INS=1284.49
garbage=0
total_execution_walltime=15.642s
REALTIME_MAIN_END_PERF_FORMAT=6987259.983228839
[time_cmd_output] time_elapsed_sec=15.81, faults_major=0, faults_minor=289523, mem_maxresident_kb=1144960, user_cputime=731.71, sys_cputime=2.96, percent_cpu=4645%
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