__step=000058
__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" ./drachsler_pext_bst_lock.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=data000058.txt
__path_data=data/data000058.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979464.436408492
binary=./drachsler_pext_bst_lock.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=drachsler_pext_bst_lock
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=192
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=6815938 prefilling_elapsed_millis=1.001s
prefilling_size=7111445 prefilling_elapsed_millis=1.101s
prefilling_size=7378008 prefilling_elapsed_millis=1.201s
prefilling_size=7618648 prefilling_elapsed_millis=1.301s
prefilling_size=7836162 prefilling_elapsed_millis=1.402s
prefilling_size=8029034 prefilling_elapsed_millis=1.502s
prefilling_size=8207409 prefilling_elapsed_millis=1.602s
prefilling_size=8369120 prefilling_elapsed_millis=1.702s
prefilling_size=8514581 prefilling_elapsed_millis=1.802s
prefilling_size=8646010 prefilling_elapsed_millis=1.903s
prefilling_size=8767123 prefilling_elapsed_millis=2.003s
prefilling_size=8876406 prefilling_elapsed_millis=2.103s
prefilling_size=8975951 prefilling_elapsed_millis=2.203s
prefilling_size=9068536 prefilling_elapsed_millis=2.303s
prefilling_size=9150550 prefilling_elapsed_millis=2.404s
prefilling_size=9225405 prefilling_elapsed_millis=2.504s
prefilling_size=9293782 prefilling_elapsed_millis=2.604s
prefilling_size=9356095 prefilling_elapsed_millis=2.704s
prefilling_size=9412449 prefilling_elapsed_millis=2.804s
prefilling_size=9465522 prefilling_elapsed_millis=2.905s
prefilling_size=9512535 prefilling_elapsed_millis=3.005s
prefilling_size=9554253 prefilling_elapsed_millis=3.105s
prefilling_size=9594275 prefilling_elapsed_millis=3.205s
prefilling_size=9630291 prefilling_elapsed_millis=3.305s
prefilling_size=9663695 prefilling_elapsed_millis=3.406s
prefilling_size=9693711 prefilling_elapsed_millis=3.506s
prefilling_size=9720872 prefilling_elapsed_millis=3.606s
prefilling_size=9746183 prefilling_elapsed_millis=3.706s
prefilling_size=9768069 prefilling_elapsed_millis=3.806s
prefilling_size=9789342 prefilling_elapsed_millis=3.907s
prefilling_size=9808372 prefilling_elapsed_millis=4.007s
finished prefilling to size 9808378 for expected size 10000000 keysum=98069924858244, performing 78878335 updates; total_prefilling_elapsed_ms=4108 ms)
pref_size=9808378
pref_millis=4108
prefill_elapsed_ms=4129
6node_tIxPvE_object_size=192
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=3584

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979468.710312614
REALTIME_END_PERF_FORMAT=6979478.710444883

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

tree_stats_computeWalltime=0.144s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 508 1000 1934 3663 6798 12070 20623 33916 53422 80536 115866 159385 210556 266845 325111 380328 428207 463707 483138 485070 471088 441599 399506 349459 295797 243768 194309 150208 112659 82238 58322 40232 27007 18161 11874 7591 4621 2737 1556 792 422 203 93 31 14 7 3 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 9 40 117 293 846 1921 4042 7840 14203 24269 38683 57579 82086 110584 143082 175578 207240 234108 252883 261080 262190 252613 234508 210272 181129 152394 123705 97295 74033 54873 39507 27765 18450 12620 8369 5367 3362 2005 1194 586 322 161 76 25 14 5 1 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 510 1009 1974 3780 7091 12916 22544 37958 61262 94739 140135 198068 268135 348931 435695 523410 603785 670947 717246 737953 732168 703789 652119 583967 506069 424897 346703 273913 209954 156271 113195 79739 54772 36611 24494 15960 9988 6099 3561 1986 1008 525 254 107 39 21 8 3 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 510 1009 1974 3780 7091 12916 22544 37958 61262 94739 140135 198068 268135 348931 435695 523410 603785 670947 717246 737953 732168 703789 652119 583967 506069 424897 346703 273913 209954 156271 113195 79739 54772 36611 24494 15960 9988 6099 3561 1986 1008 525 254 107 39 21 8 3 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99219 1.98235 1.96531 1.93516 1.90688 1.86278 1.81093 1.76894 1.72043 1.67443 1.62909 1.58659 1.54905 1.51606 1.48391 1.45513 1.42693 1.40203 1.37788 1.35527 1.33484 1.31782 1.29912 1.28286 1.26818 1.2551 1.24226 1.2296 1.21812 1.20772 1.1981 1.1892 1.18234 1.17535 1.17298 1.16682 1.15019 1.14798 1.1351 1.12075 1.10876 1.10218 1.09714 1.05512 1.07477 1.17949 1.04762 1 1 1

tree_stats_height=58
tree_stats_numInternals=6447493
tree_stats_numLeaves=3379328
tree_stats_numNodes=9826821
tree_stats_numKeys=9826821

tree_stats_avgDegreeInternal=1.52413
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34389
tree_stats_avgKeyDepth=28.6424

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 49152 97920 193728 379008 725760 1361472 2479872 4328448 7287936 11762304 18189888 26905920 38029056 51481920 66994752 83653440 100494720 115926720 128821824 137711232 141686976 140576256 135127488 125206848 112121664 97165248 81580224 66566976 52591296 40311168 30004032 21733440 15309888 10516224 7029312 4702848 3064320 1917696 1171008 683712 381312 193536 100800 48768 20544 7488 4032 1536 576 384
tree_stats_sizeInBytes=1886749632

6node_tIxPvE_object_size=192
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=13020


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:165259 2:128651 4:12418 8:38
    [2^00, 2^01]: 165259
    (2^01, 2^02]: 128651
    (2^02, 2^03]: 12418
    (2^03, 2^04]: 38
sum_limbo_reclamation_event_size_total=510907
count_limbo_reclamation_event_size_by_thread=6304 6292 6356 6363 6382 6375 6389 6429 6400 6447 6568 6393 6373 6362 6495 6342 6432 6390 6285 6313 6342 6404 6348 6308 6367 6374 6394 6359 6395 6363 6373 6385 6400 6414 6400 6356 6354 6403 6483 6373 6405 6425 6316 6377 6446 6437 6390 6285
count_limbo_reclamation_event_size_total=306366
sum_limbo_reclamation_event_count_total=452928
first_thread_announced_epoch_by_thread=26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040 26040
sum_num_inserts_by_thread=21473 21402 21697 21884 21835 21478 21474 21587 21900 21937 21480 21327 21565 21761 21889 22008 21813 21739 21728 21531 21965 22047 21687 21253 21450 21755 21873 22023 21782 21434 21498 21931 21965 22210 21201 21589 21443 21654 21682 21695 21807 21519 21489 21382 21522 21820 21928 21546
sum_num_inserts_total=1040658
sum_num_deletes_by_thread=21418 21496 21776 21767 21807 21497 21605 21649 21693 21863 21851 21585 21707 21510 21970 21779 21901 21467 21529 21342 21447 22119 21407 21377 21424 21652 21694 21725 21652 21522 21451 21721 22004 21921 21405 21656 21682 21629 22036 21880 21516 21681 21512 21733 21907 21971 21333 21462
sum_num_deletes_total=1039731
sum_num_searches_by_thread=4242173 4284164 4322909 4322535 4300619 4235051 4259800 4286771 4342135 4356758 4282432 4251701 4275572 4303130 4332779 4341953 4305142 4267623 4241678 4264263 4314209 4333366 4268694 4229447 4251635 4271499 4313114 4312698 4300354 4235752 4269313 4285099 4353646 4355529 4282972 4263495 4278660 4294366 4334901 4335549 4305193 4277300 4241170 4262279 4311665 4329131 4276150 4237666
sum_num_searches_total=205944040
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4285064 4327062 4366382 4366186 4344261 4278026 4302879 4330007 4385728 4400558 4325763 4294613 4318844 4346401 4376638 4385740 4348856 4310829 4284935 4307136 4357621 4377532 4311788 4272077 4294509 4314906 4356681 4356446 4343788 4278708 4312262 4328751 4397615 4399660 4325578 4306740 4321785 4337649 4378619 4379124 4348516 4320500 4284171 4305394 4355094 4372922 4319411 4280674
average_num_operations_total=4333842
stdev_num_operations_total=5284
sum_num_operations_total=208024429
min_num_operations_total=4272077
max_num_operations_total=4400558
first_time_thread_terminate_by_thread=10000133 10000133 10000133 10000133 10000133 10000133 10000132 10000132 10000133 10000133 10000132 10000133 10000133 10000133 10000132 10000133 10000132 10000133 10000133 10000132 10000132 10000133 10000132 10000133 10000133 10000133 10000133 10000134 10000133 10000133 10000132 10000133 10000133 10000133 10000132 10000133 10000133 10000132 10000132 10000133 10000132 10000133 10000133 10000132 10000132 10000133 10000132 10000133
min_time_thread_terminate_total=10000132
max_time_thread_terminate_total=10000134
first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 4 4
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=98255057773420
threads_final_size=9826821
final_keysum=98255057773420
final_size=9826821
validate_result=success
Validation OK.
Structural validation OK.

total_find=205944040
total_rq=0
total_inserts=1040658
total_deletes=1039731
total_updates=2080389
total_queries=205944040
total_ops=208024429
find_throughput=20594404
rq_throughput=0
update_throughput=208038
query_throughput=20594404
total_throughput=20802442


total find                    : 205944040
total rq                      : 0
total inserts                 : 1040658
total deletes                 : 1039731
total updates                 : 2080389
total queries                 : 205944040
total ops                     : 208024429
find throughput               : 20594404
rq throughput                 : 0
update throughput             : 208038
query throughput              : 20594404
total throughput              : 20802442

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=31.9788
PAPI_L3_TCM=8.02901
PAPI_TOT_CYC=6434.28
PAPI_TOT_INS=398.597
garbage=1011002331702076
total_execution_walltime=14.521s
REALTIME_MAIN_END_PERF_FORMAT=6979478.959643831
[time_cmd_output] time_elapsed_sec=14.83, faults_major=0, faults_minor=540894, mem_maxresident_kb=2165500, user_cputime=708.49, sys_cputime=6.03, percent_cpu=4816%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2