__step=000762
__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 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=data000762.txt
__path_data=data/data000762.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6988154.215445879
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=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
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.006s
prefilling_size=7380684 prefilling_elapsed_millis=1.001s
prefilling_size=7669855 prefilling_elapsed_millis=1.101s
prefilling_size=7927607 prefilling_elapsed_millis=1.201s
prefilling_size=8153682 prefilling_elapsed_millis=1.301s
prefilling_size=8354118 prefilling_elapsed_millis=1.402s
prefilling_size=8534253 prefilling_elapsed_millis=1.502s
prefilling_size=8696026 prefilling_elapsed_millis=1.602s
prefilling_size=8838480 prefilling_elapsed_millis=1.702s
prefilling_size=8965438 prefilling_elapsed_millis=1.802s
prefilling_size=9076507 prefilling_elapsed_millis=1.903s
prefilling_size=9176450 prefilling_elapsed_millis=2.003s
prefilling_size=9262800 prefilling_elapsed_millis=2.103s
prefilling_size=9341108 prefilling_elapsed_millis=2.203s
prefilling_size=9412899 prefilling_elapsed_millis=2.303s
prefilling_size=9477665 prefilling_elapsed_millis=2.404s
prefilling_size=9532783 prefilling_elapsed_millis=2.504s
prefilling_size=9582182 prefilling_elapsed_millis=2.604s
prefilling_size=9626713 prefilling_elapsed_millis=2.704s
prefilling_size=9668109 prefilling_elapsed_millis=2.804s
prefilling_size=9704013 prefilling_elapsed_millis=2.905s
prefilling_size=9735652 prefilling_elapsed_millis=3.005s
prefilling_size=9763334 prefilling_elapsed_millis=3.105s
prefilling_size=9790062 prefilling_elapsed_millis=3.205s
prefilling_size=9812379 prefilling_elapsed_millis=3.305s
finished prefilling to size 9812377 for expected size 10000000 keysum=98141338356434, performing 79416330 updates; total_prefilling_elapsed_ms=3406 ms)
pref_size=9812377
pref_millis=3406
prefill_elapsed_ms=3430
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=3615

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6988157.810218213
REALTIME_END_PERF_FORMAT=6988167.810374608

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

tree_stats_numInternalsAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262108 521900 996581 1598841 1758996 1098562 323343 33971 740 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 35 2139 42024 334181 1157266 1847217 1355800 419879 43738 905
tree_stats_numNodesAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262143 524039 1038605 1933022 2916262 2945779 1679143 453850 44478 905
tree_stats_numKeysAtDepth=0 0 2 2 1 7 10 32 72 120 248 517 1046 2047 4067 8122 16233 32961 65978 133253 280609 632674 1420206 2501129 2762627 1642265 451250 44432 905
tree_stats_avgDegreeAtDepth=1 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99999 1.99919 1.986 1.90163 1.68153 1.40693 1.19707 1.0777 1.02312 1.00369 1

tree_stats_height=29
tree_stats_numInternals=6857186
tree_stats_numLeaves=5203184
tree_stats_numNodes=12060370
tree_stats_numKeys=10000815

tree_stats_avgDegreeInternal=1.75879
tree_stats_avgDegreeLeaves=0.999964
tree_stats_avgDegree=1.43141
tree_stats_avgKeyDepth=23.2735

tree_stats_bytesAtDepth=56 56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7340032 14680008 29346184 58161880 108249232 163310672 164963624 94032008 25415600 2490768 50680
tree_stats_sizeInBytes=675380720

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:0 16:4 32:1041 64:478752 128:107
    (2^04, 2^05]: 4
    (2^05, 2^06]: 1041
    (2^06, 2^07]: 478752
    (2^07, 2^08]: 107
sum_limbo_reclamation_event_size_total=43286441
count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998
count_limbo_reclamation_event_size_total=479904
sum_limbo_reclamation_event_count_total=489024
first_thread_announced_epoch_by_thread=27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606 27606
sum_num_inserts_by_thread=2308948 2320080 2335996 2336176 2326344 2305746 2315838 2320975 2346788 2351705 2320271 2302086 2311453 2326745 2341018 2341399 2327448 2314591 2306233 2312574 2328285 2340819 2316020 2303836 2315770 2326445 2335573 2328150 2324399 2306149 2315741 2326151 2350759 2348222 2318261 2304965 2315014 2331251 2341025 2340695 2331600 2316586 2297980 2310742 2327040 2341355 2313347 2300781
sum_num_inserts_total=111529375
sum_num_deletes_by_thread=2309649 2319569 2334972 2337298 2329988 2302501 2318201 2320279 2349949 2351712 2320782 2302557 2312938 2326084 2342857 2339639 2331319 2318091 2305514 2309570 2330638 2338703 2310730 2297257 2315704 2326513 2334147 2331447 2326821 2308767 2316986 2322889 2349629 2349853 2317006 2305361 2317562 2334141 2345428 2339853 2329859 2315513 2299485 2307367 2327195 2343438 2315082 2297988
sum_num_deletes_total=111538831
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4618597 4639649 4670968 4673474 4656332 4608247 4634039 4641254 4696737 4703417 4641053 4604643 4624391 4652829 4683875 4681038 4658767 4632682 4611747 4622144 4658923 4679522 4626750 4601093 4631474 4652958 4669720 4659597 4651220 4614916 4632727 4649040 4700388 4698075 4635267 4610326 4632576 4665392 4686453 4680548 4661459 4632099 4597465 4618109 4654235 4684793 4628429 4598769
average_num_operations_total=4647254
stdev_num_operations_total=4289
sum_num_operations_total=223068206
min_num_operations_total=4597465
max_num_operations_total=4703417
first_time_thread_terminate_by_thread=10000156 10000156 10000156 10000156 10000156 10000156 10000155 10000156 10000155 10000155 10000155 10000156 10000156 10000156 10000156 10000156 10000156 10000156 10000156 10000156 10000157 10000157 10000155 10000156 10000156 10000157 10000156 10000156 10000156 10000156 10000155 10000155 10000156 10000157 10000156 10000156 10000156 10000156 10000157 10000156 10000157 10000156 10000156 10000156 10000157 10000156 10000155 10000156
min_time_thread_terminate_total=10000155
max_time_thread_terminate_total=10000157
first_time_thread_start_by_thread=4 3 3 3 4 4 4 3 3 3 4 3 3 3 3 3 3 4 3 3 3 3 4 4 4 3 4 3 4 4 4 3 3 3 4 3 3 3 3 3 3 4 3 3 3 3 4 4
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=100016156804710
threads_final_size=10000815
final_keysum=100016156804710
final_size=10000815
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=111529375
total_deletes=111538831
total_updates=223068206
total_queries=0
total_ops=223068206
find_throughput=0
rq_throughput=0
update_throughput=22306820
query_throughput=0
total_throughput=22306820


total find                    : 0
total rq                      : 0
total inserts                 : 111529375
total deletes                 : 111538831
total updates                 : 223068206
total queries                 : 0
total ops                     : 223068206
find throughput               : 0
rq throughput                 : 0
update throughput             : 22306820
query throughput              : 0
total throughput              : 22306820

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=34.3655
PAPI_L3_TCM=12.1569
PAPI_TOT_CYC=5997.91
PAPI_TOT_INS=1979.45
garbage=0
total_execution_walltime=13.743s
REALTIME_MAIN_END_PERF_FORMAT=6988167.959906119
[time_cmd_output] time_elapsed_sec=13.92, faults_major=0, faults_minor=269971, mem_maxresident_kb=1081724, user_cputime=674.25, sys_cputime=3.48, percent_cpu=4866%
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