__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 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=data000089.txt
__path_data=data/data000089.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979884.121020272
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.004s
prefilling_size=7398794 prefilling_elapsed_millis=1.001s
prefilling_size=7686225 prefilling_elapsed_millis=1.101s
prefilling_size=7941644 prefilling_elapsed_millis=1.201s
prefilling_size=8169105 prefilling_elapsed_millis=1.301s
prefilling_size=8369616 prefilling_elapsed_millis=1.401s
prefilling_size=8546213 prefilling_elapsed_millis=1.502s
prefilling_size=8704652 prefilling_elapsed_millis=1.602s
prefilling_size=8845941 prefilling_elapsed_millis=1.702s
prefilling_size=8970365 prefilling_elapsed_millis=1.802s
prefilling_size=9081533 prefilling_elapsed_millis=1.902s
prefilling_size=9179514 prefilling_elapsed_millis=2.003s
prefilling_size=9269076 prefilling_elapsed_millis=2.103s
prefilling_size=9347048 prefilling_elapsed_millis=2.203s
prefilling_size=9417144 prefilling_elapsed_millis=2.303s
prefilling_size=9478815 prefilling_elapsed_millis=2.404s
prefilling_size=9534391 prefilling_elapsed_millis=2.504s
prefilling_size=9583914 prefilling_elapsed_millis=2.604s
prefilling_size=9628707 prefilling_elapsed_millis=2.704s
prefilling_size=9667144 prefilling_elapsed_millis=2.804s
prefilling_size=9702332 prefilling_elapsed_millis=2.905s
prefilling_size=9734681 prefilling_elapsed_millis=3.005s
prefilling_size=9763861 prefilling_elapsed_millis=3.105s
prefilling_size=9790885 prefilling_elapsed_millis=3.205s
prefilling_size=9813674 prefilling_elapsed_millis=3.305s
finished prefilling to size 9813650 for expected size 10000000 keysum=98127845114372, performing 79845218 updates; total_prefilling_elapsed_ms=3406 ms)
pref_size=9813650
pref_millis=3406
prefill_elapsed_ms=3437
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=3630

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979887.702651155
REALTIME_END_PERF_FORMAT=6979897.702791274

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

tree_stats_numInternalsAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262112 521691 992960 1585536 1719163 1023984 284633 30348 755 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 32 2370 44682 337815 1164916 1835003 1268998 369577 39276 941
tree_stats_numNodesAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524061 1037642 1923351 2884079 2858987 1553631 399925 40031 941
tree_stats_numKeysAtDepth=0 1 0 3 4 7 16 32 63 120 264 496 1084 2071 4088 8171 16775 33143 67067 136474 289729 653780 1445587 2510701 2704259 1523430 397779 39991 941
tree_stats_avgDegreeAtDepth=1 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99926 1.98452 1.89664 1.67514 1.3952 1.18524 1.0742 1.02421 1.00465 1

tree_stats_height=29
tree_stats_numInternals=6683326
tree_stats_numLeaves=5063610
tree_stats_numNodes=11746936
tree_stats_numKeys=9836076

tree_stats_avgDegreeInternal=1.75765
tree_stats_avgDegreeLeaves=0.999978
tree_stats_avgDegree=1.43105
tree_stats_avgKeyDepth=23.2177

tree_stats_bytesAtDepth=56 56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7340032 14680064 29347416 58107952 107707656 161508424 160103272 87003336 22395800 2241736 52696
tree_stats_sizeInBytes=657828416

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:147227 2:99616 4:11443 8:25
    [2^00, 2^01]: 147227
    (2^01, 2^02]: 99616
    (2^02, 2^03]: 11443
    (2^03, 2^04]: 25
sum_limbo_reclamation_event_size_total=423119
count_limbo_reclamation_event_size_by_thread=5347 5446 5426 5402 5305 5459 5239 5384 5458 5418 5386 5369 5437 5361 5416 5421 5355 5356 5416 5364 5446 5377 5370 5429 5401 5392 5293 5431 5441 5400 5354 5359 5433 5390 5400 5311 5348 5378 5282 5451 5376 5324 5379 5379 5375 5379 5361 5287
count_limbo_reclamation_event_size_total=258311
sum_limbo_reclamation_event_count_total=573456
first_thread_announced_epoch_by_thread=31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154 31154
sum_num_inserts_by_thread=27452 27155 27502 27452 27342 27286 27427 27435 27580 27487 27200 27343 27477 27556 27882 27909 27714 27388 27667 27497 27485 27979 27297 26932 27449 27290 27934 27748 27650 27322 27861 27850 27637 28051 27787 27409 27512 27807 27809 27871 27785 27392 27267 27111 27526 27858 27319 27211
sum_num_inserts_total=1321900
sum_num_deletes_by_thread=27437 27522 27633 27549 27261 27465 26995 27510 27885 27760 27797 27264 27475 27828 27628 27606 27671 27408 27448 27399 27662 27865 27141 27383 27475 27636 27295 28047 27756 27466 27766 27758 27726 27656 27512 27309 27489 27409 27753 27611 27665 27652 27153 27470 27575 27625 27330 27292
sum_num_deletes_total=1322018
sum_num_searches_by_thread=5433343 5445338 5469426 5473466 5435790 5386072 5412796 5437416 5498770 5527794 5430907 5403087 5416664 5474682 5481207 5484036 5478203 5415656 5414610 5422027 5474148 5498565 5413717 5399823 5417836 5459415 5496656 5493782 5440851 5399720 5437873 5465474 5499154 5526737 5459758 5434210 5452906 5483700 5510473 5504081 5475540 5445041 5383828 5441652 5449574 5472619 5429270 5391888
sum_num_searches_total=261699581
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5488232 5500015 5524561 5528467 5490393 5440823 5467218 5492361 5554235 5583041 5485904 5457694 5471616 5530066 5536717 5539551 5533588 5470452 5469725 5476923 5529295 5554409 5468155 5454138 5472760 5514341 5551885 5549577 5496257 5454508 5493500 5521082 5554517 5582444 5515057 5488928 5507907 5538916 5566035 5559563 5530990 5500085 5438248 5496233 5504675 5528102 5483919 5446391
average_num_operations_total=5507156
stdev_num_operations_total=5534
sum_num_operations_total=264343499
min_num_operations_total=5438248
max_num_operations_total=5583041
first_time_thread_terminate_by_thread=10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139
min_time_thread_terminate_total=10000139
max_time_thread_terminate_total=10000139
first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4
min_time_thread_start_total=4
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=98345127328787
threads_final_size=9836076
final_keysum=98345127328787
final_size=9836076
validate_result=success
Validation OK.
Structural validation OK.

total_find=261699581
total_rq=0
total_inserts=1321900
total_deletes=1322018
total_updates=2643918
total_queries=261699581
total_ops=264343499
find_throughput=26169958
rq_throughput=0
update_throughput=264391
query_throughput=26169958
total_throughput=26434349


total find                    : 261699581
total rq                      : 0
total inserts                 : 1321900
total deletes                 : 1322018
total updates                 : 2643918
total queries                 : 261699581
total ops                     : 264343499
find throughput               : 26169958
rq throughput                 : 0
update throughput             : 264391
query throughput              : 26169958
total throughput              : 26434349

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=29.1854
PAPI_L3_TCM=9.74049
PAPI_TOT_CYC=5062.09
PAPI_TOT_INS=1229.46
garbage=1285640218330096
total_execution_walltime=13.731s
REALTIME_MAIN_END_PERF_FORMAT=6979897.853682963
[time_cmd_output] time_elapsed_sec=13.91, faults_major=0, faults_minor=263895, mem_maxresident_kb=1057508, user_cputime=673.44, sys_cputime=3.30, percent_cpu=4865%
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=1