__step=000364
__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 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=data000364.txt
__path_data=data/data000364.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983165.199205613
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=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.025s
prefilling_size=3708011 prefilling_elapsed_millis=1.001s
prefilling_size=3991483 prefilling_elapsed_millis=1.101s
prefilling_size=4260616 prefilling_elapsed_millis=1.201s
prefilling_size=4520360 prefilling_elapsed_millis=1.301s
prefilling_size=4765842 prefilling_elapsed_millis=1.402s
prefilling_size=4997243 prefilling_elapsed_millis=1.502s
prefilling_size=5222979 prefilling_elapsed_millis=1.602s
prefilling_size=5435984 prefilling_elapsed_millis=1.702s
prefilling_size=5637094 prefilling_elapsed_millis=1.802s
prefilling_size=5831904 prefilling_elapsed_millis=1.903s
prefilling_size=6015898 prefilling_elapsed_millis=2.003s
prefilling_size=6192124 prefilling_elapsed_millis=2.103s
prefilling_size=6361531 prefilling_elapsed_millis=2.203s
prefilling_size=6521506 prefilling_elapsed_millis=2.303s
prefilling_size=6672593 prefilling_elapsed_millis=2.403s
prefilling_size=6819295 prefilling_elapsed_millis=2.504s
prefilling_size=6959249 prefilling_elapsed_millis=2.604s
prefilling_size=7092151 prefilling_elapsed_millis=2.704s
prefilling_size=7221583 prefilling_elapsed_millis=2.804s
prefilling_size=7341981 prefilling_elapsed_millis=2.904s
prefilling_size=7457072 prefilling_elapsed_millis=3.004s
prefilling_size=7568825 prefilling_elapsed_millis=3.105s
prefilling_size=7672850 prefilling_elapsed_millis=3.205s
prefilling_size=7773933 prefilling_elapsed_millis=3.305s
prefilling_size=7870926 prefilling_elapsed_millis=3.405s
prefilling_size=7962286 prefilling_elapsed_millis=3.505s
prefilling_size=8048927 prefilling_elapsed_millis=3.606s
prefilling_size=8132574 prefilling_elapsed_millis=3.706s
prefilling_size=8212115 prefilling_elapsed_millis=3.806s
prefilling_size=8288909 prefilling_elapsed_millis=3.906s
prefilling_size=8363226 prefilling_elapsed_millis=4.006s
prefilling_size=8431601 prefilling_elapsed_millis=4.107s
prefilling_size=8497954 prefilling_elapsed_millis=4.207s
prefilling_size=8562683 prefilling_elapsed_millis=4.307s
prefilling_size=8624045 prefilling_elapsed_millis=4.407s
prefilling_size=8681655 prefilling_elapsed_millis=4.507s
prefilling_size=8736840 prefilling_elapsed_millis=4.608s
prefilling_size=8789652 prefilling_elapsed_millis=4.708s
prefilling_size=8840794 prefilling_elapsed_millis=4.808s
prefilling_size=8889101 prefilling_elapsed_millis=4.908s
prefilling_size=8935477 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 1064501 keys with 96 threads...
tid=0 thread_prefill_amount=112482 percent_done_estimate=1014.4 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=114565 percent_done_estimate=1033.2 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=116648 percent_done_estimate=1052.0 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=118731 percent_done_estimate=1070.8 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=120814 percent_done_estimate=1089.5 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.14714s
prefilling_size=10000000 prefilling_elapsed_millis=0.147s
finished prefilling to size 10000000 for expected size 10000000 keysum=99993983578610, performing 49075930 updates; total_prefilling_elapsed_ms=147 ms)
pref_size=10000000
pref_millis=147
prefill_elapsed_ms=147
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6983170.684160073
REALTIME_END_PERF_FORMAT=6983180.684254055

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

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=10009668
tree_stats_avgDegreeAtDepth=1.00097e+07

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=1.00097e+07
tree_stats_avgDegree=1.00097e+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=210503 212090 213066 214266 215664 213553 212397 212216 216161 218130 214888 215294 213442 213685 212902 215531 215237 215835 211752 214059 214024 214879 214433 214184 212524 212518 213209 214659 214233 213612 212273 212249 216388 217374 214860 214667 214114 213080 212860 214634 215047 215565 212673 213111 214020 215253 215027 214588 215395 215554 216772 219355 216991 217159 214947 216520 218581 221247 218841 219216 216634 218039 216095 217485 217561 218621 215648 214865 216916 219151 218894 217202 216024 216248 216205 218855 217562 218033 214962 217756 218241 222421 220400 219645 218053 218456 214793 219361 217010 217930 213881 214894 216587 218961 217651 217385
sum_num_inserts_total=20715732
sum_num_deletes_by_thread=211613 213689 213977 214343 215167 213748 213021 213038 216160 218331 215823 215184 213934 212601 213766 215451 215211 216231 210960 213313 213623 215164 215800 214191 212774 212667 212165 214491 214864 213816 213260 213176 214727 217880 215279 214355 214123 213937 213162 215513 215132 215901 211446 213295 213014 216459 214592 213632 214969 215917 216739 218481 216674 216186 214839 217267 218889 220946 219777 219466 216375 218046 215964 219339 217088 216721 215395 214835 216101 217800 217990 217204 216039 215957 216428 219028 217254 217537 215695 217170 219534 221938 220435 220220 217372 219083 216640 219695 217708 217064 214572 215200 216179 218461 218785 217025
sum_num_deletes_total=20722026
sum_num_searches_by_thread=3810967 3827702 3836725 3855384 3863608 3846335 3825986 3819694 3890514 3919785 3866677 3868667 3839277 3839664 3838630 3881206 3879385 3884347 3814399 3842058 3853674 3880128 3857849 3862131 3820998 3828508 3837712 3860973 3867695 3842552 3828608 3819623 3882791 3915183 3873652 3856550 3849124 3839884 3840717 3867321 3870904 3879672 3807145 3836577 3853653 3884467 3865991 3861209 3870484 3883141 3891595 3940169 3908655 3908468 3876449 3897339 3933202 3982816 3948430 3937486 3899159 3920231 3884414 3927468 3911749 3924140 3865848 3862934 3900255 3946050 3934144 3912671 3883466 3888417 3891566 3943507 3912720 3921091 3887897 3904999 3952150 3999124 3975088 3962640 3922053 3935862 3891502 3945416 3907779 3917959 3865687 3868078 3899308 3943676 3931761 3909170
sum_num_searches_total=372924514
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4233083 4253481 4263768 4283993 4294439 4273636 4251404 4244948 4322835 4356246 4297388 4299145 4266653 4265950 4265298 4312188 4309833 4316413 4237111 4269430 4281321 4310171 4288082 4290506 4246296 4253693 4263086 4290123 4296792 4269980 4254141 4245048 4313906 4350437 4303791 4285572 4277361 4266901 4266739 4297468 4301083 4311138 4231264 4262983 4280687 4316179 4295610 4289429 4300848 4314612 4325106 4378005 4342320 4341813 4306235 4331126 4370672 4425009 4387048 4376168 4332168 4356316 4316473 4364292 4346398 4359482 4296891 4292634 4333272 4383001 4371028 4347077 4315529 4320622 4324199 4381390 4347536 4356661 4318554 4339925 4389925 4443483 4415923 4402505 4357478 4373401 4322935 4384472 4342497 4352953 4294140 4298172 4332074 4381098 4368197 4343580
average_num_operations_total=4316273
stdev_num_operations_total=4771
sum_num_operations_total=414362272
min_num_operations_total=4231264
max_num_operations_total=4443483
first_time_thread_terminate_by_thread=10000174 10000094 10000095 10000094 10000095 10000094 10000096 10000095 10000095 10000095 10000095 10000094 10000094 10000094 10000096 10000093 10000094 10000094 10000095 10000095 10000094 10000095 10000095 10000094 10000095 10000094 10000094 10000094 10000095 10000094 10000096 10000095 10000095 10000095 10000095 10000094 10000095 10000093 10000094 10000093 10000094 10000096 10000095 10000095 10000094 10000095 10000095 10000094 10000097 10000093 10000094 10000094 10000096 10000094 10000096 10000097 10000094 10000095 10000095 10000096 10000094 10000096 10000096 10000096 10000096 10000094 10000097 10000094 10000096 10000094 10000096 10000096 10000097 10000093 10000094 10000094 10000096 10000094 10000095 10000097 10000094 10000095 10000095 10000096 10000094 10000094 10000096 10000096 10000096 10000094 10000097 10000096 10000096 10000094 10000096 10000097
min_time_thread_terminate_total=10000093
max_time_thread_terminate_total=10000174
first_time_thread_start_by_thread=9 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 5 6 5 6 5 6 6 6 5 6 6 5 5 5 6 5 5 6 5 6 5 6 6 6 5 5 5 6 5 6 6 6 5 6 6 5 5 5 6 5 5 6 5 6 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=100096264190944
threads_final_size=10009668
final_keysum=100096264190944
final_size=10009668
validate_result=success
Validation OK.
Structural validation OK.

total_find=372924514
total_rq=0
total_inserts=20715732
total_deletes=20722026
total_updates=41437758
total_queries=372924514
total_ops=414362272
find_throughput=37292451
rq_throughput=0
update_throughput=4143775
query_throughput=37292451
total_throughput=41436227


total find                    : 372924514
total rq                      : 0
total inserts                 : 20715732
total deletes                 : 20722026
total updates                 : 41437758
total queries                 : 372924514
total ops                     : 414362272
find throughput               : 37292451
rq throughput                 : 0
update throughput             : 4143775
query throughput              : 37292451
total throughput              : 41436227

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=37.3423
PAPI_L3_TCM=16.4935
PAPI_TOT_CYC=6440.35
PAPI_TOT_INS=831.419
garbage=1826143090792599
total_execution_walltime=15.784s
REALTIME_MAIN_END_PERF_FORMAT=6983180.983963569
[time_cmd_output] time_elapsed_sec=15.98, faults_major=0, faults_minor=333388, mem_maxresident_kb=1294720, user_cputime=1469.01, sys_cputime=5.87, percent_cpu=9225%
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