__step=000089
__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" ./bronson_pext_bst_occ.debra -nwork 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000089.txt
__path_data=data/data000089.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=207933.840605330
binary=./bronson_pext_bst_occ.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=bronson_pext_bst_occ
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=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
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
sizes: node=56
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.018s
prefilling_size=7806351 prefilling_elapsed_millis=1s
prefilling_size=8077850 prefilling_elapsed_millis=1.101s
prefilling_size=8312680 prefilling_elapsed_millis=1.201s
prefilling_size=8517378 prefilling_elapsed_millis=1.301s
prefilling_size=8692398 prefilling_elapsed_millis=1.401s
prefilling_size=8848084 prefilling_elapsed_millis=1.501s
prefilling_size=8985808 prefilling_elapsed_millis=1.601s
prefilling_size=9104671 prefilling_elapsed_millis=1.701s
prefilling_size=9209657 prefilling_elapsed_millis=1.801s
prefilling_size=9299437 prefilling_elapsed_millis=1.902s
prefilling_size=9378145 prefilling_elapsed_millis=2.002s
prefilling_size=9446260 prefilling_elapsed_millis=2.102s
prefilling_size=9509577 prefilling_elapsed_millis=2.202s
prefilling_size=9563620 prefilling_elapsed_millis=2.302s
prefilling_size=9611575 prefilling_elapsed_millis=2.402s
prefilling_size=9655504 prefilling_elapsed_millis=2.502s
prefilling_size=9692823 prefilling_elapsed_millis=2.602s
prefilling_size=9727462 prefilling_elapsed_millis=2.703s
prefilling_size=9756300 prefilling_elapsed_millis=2.803s
prefilling_size=9783484 prefilling_elapsed_millis=2.903s
prefilling_size=9808525 prefilling_elapsed_millis=3.004s
finished prefilling to size 9808545 for expected size 10000000 keysum=98085845459333, performing 85394030 updates; total_prefilling_elapsed_ms=3104 ms)
pref_size=9808545
pref_millis=3104
prefill_elapsed_ms=3117
6node_tIxPvE_object_size=56
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0
global_epoch_counter=2184
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=207936.996615721
REALTIME_END_PERF_FORMAT=207946.996707451
###############################################################################
################################## TIME IS UP #################################
###############################################################################
joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################
10s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (256 threads)...
partitioned into 1024 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.068s
tree_stats_numInternalsAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262100 521870 994938 1585187 1709959 1036531 289725 27783 419 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 43 2135 43390 342340 1170894 1813829 1287518 377304 35842 506
tree_stats_numNodesAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262143 524005 1038328 1927527 2880853 2850360 1577243 405087 36261 506
tree_stats_numKeysAtDepth=0 0 2 2 4 8 17 27 67 138 246 514 1030 2067 4138 8278 16564 33347 67296 136387 289475 650228 1446545 2501601 2688595 1546724 403153 36245 506
tree_stats_avgDegreeAtDepth=1 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99999 1.99909 1.9856 1.89816 1.67219 1.39585 1.18968 1.07313 1.02091 1.0024 1
tree_stats_height=29
tree_stats_numInternals=6690656
tree_stats_numLeaves=5073801
tree_stats_numNodes=11764457
tree_stats_numKeys=9833204
tree_stats_avgDegreeInternal=1.75834
tree_stats_avgDegreeLeaves=0.999977
tree_stats_avgDegree=1.43127
tree_stats_avgKeyDepth=23.2214
tree_stats_bytesAtDepth=56 56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7340032 14680008 29344280 58146368 107941512 161327768 159620160 88325608 22684872 2030616 28336
tree_stats_sizeInBytes=658809592
6node_tIxPvE_object_size=56
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0
global_epoch_counter=10126
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:160560 2:140177 4:24598 8:186 16:1
[2^00, 2^01]: 160560
(2^01, 2^02]: 140177
(2^02, 2^03]: 24598
(2^03, 2^04]: 186
(2^04, 2^05]: 1
sum_limbo_reclamation_event_size_total=595031
count_limbo_reclamation_event_size_by_thread=5082 5174 5074 5083 5013 5126 5169 5156 5030 5017 4942 5041 5020 5055 5043 5000 5097 5152 5158 5071 5083 5055 5117 5075 5240 5046 5082 5041 5087 5095 5156 5071 5116 5127 5045 5068 5038 5073 5051 5164 5170 4983 5035 5032 5203 5035 5153 5058 5037 4996 5168 5119 5137 5086 5113 5098 5111 5086 5079 5157 5003 5207 5079 5044
count_limbo_reclamation_event_size_total=325522
sum_limbo_reclamation_event_count_total=508288
first_thread_announced_epoch_by_thread=20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252 20252
sum_num_inserts_by_thread=24895 24365 24286 24687 24945 24310 24553 24141 24934 24719 24110 24444 24468 24512 23740 24143 24936 24114 24426 24662 24878 24673 24452 24313 23994 24299 24485 24319 24160 24267 24189 24734 24408 24924 24935 24983 24984 24841 24422 23863 23913 24520 24669 24578 24058 24822 24292 24750 24274 24725 24034 24045 23853 24317 23771 23901 23897 24440 23430 24311 24914 24146 24269 24696
sum_num_inserts_total=1562138
sum_num_deletes_by_thread=24632 24508 24129 24866 24911 24279 24401 24011 24495 24562 24335 24410 24823 24330 23939 24184 24729 24081 24331 24935 24856 24744 24249 24775 24398 24509 24424 24431 24020 24297 24445 24720 24087 25058 24665 24761 24805 24694 24621 23978 24332 24097 24624 24459 24065 24521 24360 24627 24544 24330 24423 24050 24031 24544 23711 23758 23735 24164 23597 24256 24690 24293 24798 24606
sum_num_deletes_total=1562043
sum_num_searches_by_thread=4919819 4831948 4823132 4887655 4914803 4828748 4813045 4812159 4898725 4875681 4846035 4835402 4843350 4838835 4749478 4821038 4911653 4823391 4804111 4887181 4899643 4901863 4801772 4862232 4765974 4828666 4818930 4809693 4739671 4813092 4801540 4873942 4835582 4923069 4904978 4908041 4915956 4896131 4862491 4766914 4768486 4841285 4826947 4824213 4750229 4921993 4811813 4891716 4875144 4876312 4776196 4771598 4766514 4830335 4724340 4718924 4721405 4801128 4703664 4795624 4896273 4809998 4891947 4886425
sum_num_searches_total=309378878
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4969346 4880821 4871547 4937208 4964659 4877337 4861999 4860311 4948154 4924962 4894480 4884256 4892641 4887677 4797157 4869365 4961318 4871586 4852868 4936778 4949377 4951280 4850473 4911320 4814366 4877474 4867839 4858443 4787851 4861656 4850174 4923396 4884077 4973051 4954578 4957785 4965745 4945666 4911534 4814755 4816731 4889902 4876240 4873250 4798352 4971336 4860465 4941093 4923962 4925367 4824653 4819693 4814398 4879196 4771822 4766583 4769037 4849732 4750691 4844191 4945877 4858437 4941014 4935727
average_num_operations_total=4882860
stdev_num_operations_total=7353
sum_num_operations_total=312503059
min_num_operations_total=4750691
max_num_operations_total=4973051
first_time_thread_terminate_by_thread=10000095 10000095 10000095 10000095 10000095 10000095 10000095 10000095 10000096 10000096 10000096 10000096 10000094 10000094 10000094 10000094 10000093 10000093 10000093 10000093 10000095 10000095 10000095 10000095 10000096 10000096 10000096 10000096 10000097 10000097 10000097 10000097 10000093 10000093 10000094 10000094 10000094 10000094 10000094 10000094 10000097 10000097 10000097 10000097 10000093 10000093 10000093 10000093 10000096 10000096 10000096 10000096 10000094 10000094 10000094 10000094 10000093 10000093 10000093 10000093 10000098 10000098 10000098 10000098
min_time_thread_terminate_total=10000093
max_time_thread_terminate_total=10000098
first_time_thread_start_by_thread=9 9 9 9 11 11 11 11 11 11 11 11 9 10 10 10 12 12 12 12 12 12 12 12 13 13 13 13 13 13 13 13 11 11 11 11 9 9 9 9 9 9 9 9 10 10 10 10 12 12 12 12 12 12 12 12 13 13 13 13 10 10 10 10
min_time_thread_start_total=9
max_time_thread_start_total=13
sum_duration_all_ops_total=
threads_final_keysum=98335917952298
threads_final_size=9833204
final_keysum=98335917952298
final_size=9833204
validate_result=success
Validation OK.
Structural validation OK.
total_find=309378878
total_rq=0
total_inserts=1562138
total_deletes=1562043
total_updates=3124181
total_queries=309378878
total_ops=312503059
find_throughput=30937887
rq_throughput=0
update_throughput=312418
query_throughput=30937887
total_throughput=31250305
total find : 309378878
total rq : 0
total inserts : 1562138
total deletes : 1562043
total updates : 3124181
total queries : 309378878
total ops : 312503059
find throughput : 30937887
rq throughput : 0
update throughput : 312418
query throughput : 30937887
total throughput : 31250305
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=359882295839893
total_execution_walltime=13.294s
REALTIME_MAIN_END_PERF_FORMAT=207947.135287575
[time_cmd_output] time_elapsed_sec=13.44, faults_major=0, faults_minor=264624, mem_maxresident_kb=1059196, user_cputime=856.01, sys_cputime=5.17, percent_cpu=6403%
timeout=false
algorithm=bronson_pext_bst_occ.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1