__step=000426
__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 48 -nprefill 48 -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=data000426.txt
__path_data=data/data000426.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6984024.324252628
binary=./bronson_pext_bst_occ.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
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=5
DEL_FRAC=5
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=5.0 5.0
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,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.003s
prefilling_size=7301635 prefilling_elapsed_millis=1.001s
prefilling_size=7584852 prefilling_elapsed_millis=1.101s
prefilling_size=7835950 prefilling_elapsed_millis=1.201s
prefilling_size=8056803 prefilling_elapsed_millis=1.301s
prefilling_size=8255150 prefilling_elapsed_millis=1.402s
prefilling_size=8431041 prefilling_elapsed_millis=1.502s
prefilling_size=8587290 prefilling_elapsed_millis=1.602s
prefilling_size=8729637 prefilling_elapsed_millis=1.702s
prefilling_size=8855258 prefilling_elapsed_millis=1.802s
prefilling_size=8968588 prefilling_elapsed_millis=1.903s
prefilling_size=9069020 prefilling_elapsed_millis=2.003s
prefilling_size=9160383 prefilling_elapsed_millis=2.103s
prefilling_size=9242204 prefilling_elapsed_millis=2.203s
prefilling_size=9313526 prefilling_elapsed_millis=2.303s
prefilling_size=9379537 prefilling_elapsed_millis=2.404s
prefilling_size=9440503 prefilling_elapsed_millis=2.504s
prefilling_size=9493216 prefilling_elapsed_millis=2.604s
prefilling_size=9539940 prefilling_elapsed_millis=2.704s
prefilling_size=9583012 prefilling_elapsed_millis=2.804s
prefilling_size=9622578 prefilling_elapsed_millis=2.905s
prefilling_size=9658076 prefilling_elapsed_millis=3.005s
prefilling_size=9690490 prefilling_elapsed_millis=3.105s
prefilling_size=9720575 prefilling_elapsed_millis=3.205s
prefilling_size=9747671 prefilling_elapsed_millis=3.305s
prefilling_size=9770527 prefilling_elapsed_millis=3.406s
prefilling_size=9790893 prefilling_elapsed_millis=3.506s
prefilling_size=9808199 prefilling_elapsed_millis=3.606s
finished prefilling to size 9808205 for expected size 10000000 keysum=98092868969780, performing 86946096 updates; total_prefilling_elapsed_ms=3707 ms)
pref_size=9808205
pref_millis=3707
prefill_elapsed_ms=3726
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=3981

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6984028.219496233
REALTIME_END_PERF_FORMAT=6984038.219639206

###############################################################################
################################## 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 (192 threads)...
partitioned into 1024 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.044s

tree_stats_numInternalsAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262087 521387 990985 1577457 1729338 1087726 326286 35017 752 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 56 2559 45977 341689 1145128 1811006 1340073 424314 45100 952
tree_stats_numNodesAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262143 523946 1036962 1919146 2874466 2898732 1666359 459331 45852 952
tree_stats_numKeysAtDepth=0 0 2 1 5 5 15 32 61 125 266 514 1028 2039 4118 8267 16552 32855 66746 134583 284449 641377 1420627 2469564 2718082 1629346 456648 45809 952
tree_stats_avgDegreeAtDepth=1 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99999 1.99892 1.98402 1.89508 1.67582 1.40681 1.1996 1.07983 1.02358 1.00436 1

tree_stats_height=29
tree_stats_numInternals=6793179
tree_stats_numLeaves=5156854
tree_stats_numNodes=11950033
tree_stats_numKeys=9934068

tree_stats_avgDegreeInternal=1.75912
tree_stats_avgDegreeLeaves=0.999974
tree_stats_avgDegree=1.43152
tree_stats_avgKeyDepth=23.2662

tree_stats_bytesAtDepth=56 56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7340032 14680008 29340976 58069872 107472176 160970096 162328992 93316104 25722536 2567712 53312
tree_stats_sizeInBytes=669201848

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=15817


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:1363 2:16955 4:160711 8:285975 16:14624 32:32 64:35
    [2^00, 2^01]: 1363
    (2^01, 2^02]: 16955
    (2^02, 2^03]: 160711
    (2^03, 2^04]: 285975
    (2^04, 2^05]: 14624
    (2^05, 2^06]: 32
    (2^06, 2^07]: 35
sum_limbo_reclamation_event_size_total=4212684
count_limbo_reclamation_event_size_by_thread=9992 9992 9998 9994 9992 9994 9994 9994 9996 9994 9997 9993 9994 9990 9991 9993 9997 9989 9994 9994 9994 9995 9996 9995 9994 9998 9995 9997 9996 9993 9993 9992 9993 9992 9991 9994 9994 9994 9990 9992 9998 9992 9995 9991 9993 9990 9993 9993
count_limbo_reclamation_event_size_total=479695
sum_limbo_reclamation_event_count_total=568128
first_thread_announced_epoch_by_thread=31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634 31634
sum_num_inserts_by_thread=270586 273128 269493 268279 267208 264364 265437 266324 270104 276334 267197 271344 272115 274839 275277 276431 274603 271706 271632 264334 273435 275300 265620 264668 273377 267069 275510 274502 266811 270436 265394 267573 275822 276020 273311 271719 272594 275480 269044 268932 274534 272511 264311 271750 274986 275218 265020 270049
sum_num_inserts_total=13001731
sum_num_deletes_by_thread=271929 273284 268516 268482 267129 264683 264992 267581 270311 276945 266894 271324 273264 274450 276579 275805 273843 273015 271008 264463 274424 274844 267169 262770 272354 266895 275524 274373 266855 271332 265400 266792 276498 275399 273379 270958 273348 274263 269859 268634 273305 272360 263775 272607 274084 275279 265623 268537
sum_num_deletes_total=13001137
sum_num_searches_by_thread=4896461 4920564 4836772 4828487 4812018 4770200 4790092 4807379 4857308 4973951 4797796 4874762 4911169 4935666 4966745 4962194 4938329 4901831 4880376 4750915 4932355 4955833 4782570 4749571 4907307 4804118 4953845 4946536 4814118 4883162 4787020 4809218 4971708 4974094 4918923 4889773 4909439 4937283 4847659 4840238 4932932 4898499 4754629 4896505 4922915 4948509 4775137 4855085
sum_num_searches_total=234011996
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5438976 5466976 5374781 5365248 5346355 5299247 5320521 5341284 5397723 5527230 5331887 5417430 5456548 5484955 5518601 5514430 5486775 5446552 5423016 5279712 5480214 5505977 5315359 5277009 5453038 5338082 5504879 5495411 5347784 5424930 5317814 5343583 5524028 5525513 5465613 5432450 5455381 5487026 5386562 5377804 5480771 5443370 5282715 5440862 5471985 5499006 5305780 5393671
average_num_operations_total=5416976
stdev_num_operations_total=11172
sum_num_operations_total=260014864
min_num_operations_total=5277009
max_num_operations_total=5527230
first_time_thread_terminate_by_thread=10000141 10000142 10000141 10000141 10000141 10000142 10000142 10000142 10000141 10000141 10000141 10000142 10000141 10000141 10000141 10000141 10000141 10000142 10000141 10000141 10000141 10000142 10000142 10000141 10000141 10000142 10000142 10000142 10000141 10000142 10000142 10000142 10000141 10000141 10000141 10000142 10000141 10000141 10000141 10000141 10000141 10000142 10000141 10000141 10000141 10000142 10000142 10000141
min_time_thread_terminate_total=10000141
max_time_thread_terminate_total=10000142
first_time_thread_start_by_thread=6 7 6 7 7 6 6 6 7 7 6 6 7 6 6 6 6 6 7 6 7 7 7 7 6 7 6 7 7 6 6 6 7 7 6 6 7 6 6 6 6 6 7 6 7 7 7 7
min_time_thread_start_total=6
max_time_thread_start_total=7
sum_duration_all_ops_total=

threads_final_keysum=99347534003229
threads_final_size=9934068
final_keysum=99347534003229
final_size=9934068
validate_result=success
Validation OK.
Structural validation OK.

total_find=234011996
total_rq=0
total_inserts=13001731
total_deletes=13001137
total_updates=26002868
total_queries=234011996
total_ops=260014864
find_throughput=23401199
rq_throughput=0
update_throughput=2600286
query_throughput=23401199
total_throughput=26001486


total find                    : 234011996
total rq                      : 0
total inserts                 : 13001731
total deletes                 : 13001137
total updates                 : 26002868
total queries                 : 234011996
total ops                     : 260014864
find throughput               : 23401199
rq throughput                 : 0
update throughput             : 2600286
query throughput              : 23401199
total throughput              : 26001486

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=29.5008
PAPI_L3_TCM=9.92579
PAPI_TOT_CYC=5146.92
PAPI_TOT_INS=1294.92
garbage=1158977166545830
total_execution_walltime=14.03s
REALTIME_MAIN_END_PERF_FORMAT=6984038.355833841
[time_cmd_output] time_elapsed_sec=14.24, faults_major=0, faults_minor=267222, mem_maxresident_kb=1070900, user_cputime=687.85, sys_cputime=4.12, percent_cpu=4859%
timeout=false
algorithm=bronson_pext_bst_occ.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2