__step=000090
__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 0.5 0.5 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000090.txt
__path_data=data/data000090.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979898.95976403
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=0.5
DEL_FRAC=0.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=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,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.018s
prefilling_size=7387123 prefilling_elapsed_millis=1.001s
prefilling_size=7676129 prefilling_elapsed_millis=1.101s
prefilling_size=7931535 prefilling_elapsed_millis=1.201s
prefilling_size=8159274 prefilling_elapsed_millis=1.301s
prefilling_size=8358724 prefilling_elapsed_millis=1.402s
prefilling_size=8538627 prefilling_elapsed_millis=1.502s
prefilling_size=8696324 prefilling_elapsed_millis=1.602s
prefilling_size=8836296 prefilling_elapsed_millis=1.702s
prefilling_size=8961149 prefilling_elapsed_millis=1.802s
prefilling_size=9072881 prefilling_elapsed_millis=1.903s
prefilling_size=9173627 prefilling_elapsed_millis=2.003s
prefilling_size=9262071 prefilling_elapsed_millis=2.103s
prefilling_size=9341265 prefilling_elapsed_millis=2.203s
prefilling_size=9411042 prefilling_elapsed_millis=2.303s
prefilling_size=9474067 prefilling_elapsed_millis=2.403s
prefilling_size=9527877 prefilling_elapsed_millis=2.504s
prefilling_size=9578207 prefilling_elapsed_millis=2.604s
prefilling_size=9623347 prefilling_elapsed_millis=2.704s
prefilling_size=9663251 prefilling_elapsed_millis=2.804s
prefilling_size=9697622 prefilling_elapsed_millis=2.904s
prefilling_size=9731539 prefilling_elapsed_millis=3.005s
prefilling_size=9759409 prefilling_elapsed_millis=3.105s
prefilling_size=9784980 prefilling_elapsed_millis=3.205s
prefilling_size=9809127 prefilling_elapsed_millis=3.305s
finished prefilling to size 9809154 for expected size 10000000 keysum=98090887974305, performing 79602267 updates; total_prefilling_elapsed_ms=3406 ms)
pref_size=9809154
pref_millis=3406
prefill_elapsed_ms=3427
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=3617

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979901.713973466
REALTIME_END_PERF_FORMAT=6979911.714120496

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

tree_stats_numInternalsAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262083 520960 987978 1573618 1718109 1046576 283846 23619 165 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 57 2954 47451 338883 1146476 1817758 1304042 370527 30324 191
tree_stats_numNodesAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262140 523914 1035429 1912501 2864585 2864334 1587888 394146 30489 191
tree_stats_numKeysAtDepth=0 0 2 2 3 5 18 32 74 127 259 509 1069 2049 4205 8374 16738 33600 67146 137242 290865 653529 1436481 2488051 2706325 1559901 392677 30487 191
tree_stats_avgDegreeAtDepth=1 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99998 1.99882 1.98197 1.89289 1.67501 1.40012 1.18897 1.06945 1.01742 1.00085 1

tree_stats_height=29
tree_stats_numInternals=6679097
tree_stats_numLeaves=5058664
tree_stats_numNodes=11737761
tree_stats_numKeys=9829961

tree_stats_avgDegreeInternal=1.75739
tree_stats_avgDegreeLeaves=0.99998
tree_stats_avgDegree=1.43096
tree_stats_avgKeyDepth=23.2194

tree_stats_bytesAtDepth=56 56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7340032 14679840 29339184 57984024 107100056 160416760 160402704 88921728 22072176 1707384 10696
tree_stats_sizeInBytes=657314616

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:146619 2:100022 4:11454 8:37
    [2^00, 2^01]: 146619
    (2^01, 2^02]: 100022
    (2^02, 2^03]: 11454
    (2^03, 2^04]: 37
sum_limbo_reclamation_event_size_total=423860
count_limbo_reclamation_event_size_by_thread=5383 5345 5527 5400 5380 5320 5385 5336 5470 5423 5332 5346 5406 5506 5439 5402 5328 5280 5341 5393 5420 5413 5338 5387 5379 5385 5421 5329 5330 5207 5395 5340 5427 5390 5421 5416 5333 5386 5481 5384 5250 5412 5289 5337 5406 5448 5405 5261
count_limbo_reclamation_event_size_total=258132
sum_limbo_reclamation_event_count_total=573936
first_thread_announced_epoch_by_thread=31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148 31148
sum_num_inserts_by_thread=27395 27555 27734 27437 27448 27302 27202 27254 28084 28247 27645 27225 27419 27693 27443 27845 27698 27119 27372 27012 27834 27572 27389 27207 27626 27521 27624 27941 27675 27499 27204 27522 27667 27704 27751 27481 27748 27781 27959 27606 27763 27391 27217 27510 27419 27389 27481 27190
sum_num_inserts_total=1321800
sum_num_deletes_by_thread=27498 27590 27833 27415 27577 27124 27347 27309 28063 27908 27320 27197 27739 27664 27536 27869 27761 27105 27610 27578 27415 27639 27345 27129 27487 27721 27815 28163 27752 27150 27340 27489 27893 27789 27701 27670 27368 27826 27897 27663 27669 27480 27293 27758 27758 27863 27297 27374
sum_num_deletes_total=1323787
sum_num_searches_by_thread=5449728 5445335 5503983 5486575 5456119 5427409 5430844 5451255 5531864 5533081 5442677 5416430 5466326 5459285 5495629 5494464 5486000 5423516 5426561 5429620 5479289 5481920 5421065 5404695 5454158 5437040 5484792 5509721 5482730 5430948 5427564 5453008 5497141 5517161 5456382 5410314 5452663 5478944 5502994 5477117 5481509 5419430 5414615 5444830 5475401 5479206 5437590 5391149
sum_num_searches_total=262060077
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5504621 5500480 5559550 5541427 5511144 5481835 5485393 5505818 5588011 5589236 5497642 5470852 5521484 5514642 5550608 5550178 5541459 5477740 5481543 5484210 5534538 5537131 5475799 5459031 5509271 5492282 5540231 5565825 5538157 5485597 5482108 5508019 5552701 5572654 5511834 5465465 5507779 5534551 5558850 5532386 5536941 5474301 5469125 5500098 5530578 5534458 5492368 5445713
average_num_operations_total=5514701
stdev_num_operations_total=5078
sum_num_operations_total=264705664
min_num_operations_total=5445713
max_num_operations_total=5589236
first_time_thread_terminate_by_thread=10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146
min_time_thread_terminate_total=10000146
max_time_thread_terminate_total=10000146
first_time_thread_start_by_thread=6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6
min_time_thread_start_total=6
max_time_thread_start_total=6
sum_duration_all_ops_total=

threads_final_keysum=98300172191303
threads_final_size=9829961
final_keysum=98300172191303
final_size=9829961
validate_result=success
Validation OK.
Structural validation OK.

total_find=262060077
total_rq=0
total_inserts=1321800
total_deletes=1323787
total_updates=2645587
total_queries=262060077
total_ops=264705664
find_throughput=26206007
rq_throughput=0
update_throughput=264558
query_throughput=26206007
total_throughput=26470566


total find                    : 262060077
total rq                      : 0
total inserts                 : 1321800
total deletes                 : 1323787
total updates                 : 2645587
total queries                 : 262060077
total ops                     : 264705664
find throughput               : 26206007
rq throughput                 : 0
update throughput             : 264558
query throughput              : 26206007
total throughput              : 26470566

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=29.1403
PAPI_L3_TCM=9.74456
PAPI_TOT_CYC=5055.13
PAPI_TOT_INS=1229.8
garbage=1286770092294903
total_execution_walltime=13.753s
REALTIME_MAIN_END_PERF_FORMAT=6979911.863280977
[time_cmd_output] time_elapsed_sec=13.95, faults_major=0, faults_minor=263808, mem_maxresident_kb=1056964, user_cputime=672.70, sys_cputime=2.99, percent_cpu=4842%
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