__step=000505
__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 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000505.txt
__path_data=data/data000505.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6984981.639844463
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=5
DEL_FRAC=5
RQ=0
RQSIZE=0
MAXKEY=2000000
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=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.005s
prefilling_size=999701 prefilling_elapsed_millis=1.001s
finished prefilling to size 999699 for expected size 1000000 keysum=999245206516, performing 35600503 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=999699
pref_millis=1102
prefill_elapsed_ms=1120
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=1639

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6984982.914147014
REALTIME_END_PERF_FORMAT=6984992.914286300

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

tree_stats_computeWalltime=0.038s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 252 487 892 1576 2733 4511 7194 10910 16006 22337 29615 37505 45140 51541 55811 57161 55709 51909 46454 39706 32392 25270 19089 13778 9588 6287 3949 2496 1492 869 442 215 100 41 10 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 3 10 45 113 240 518 1031 1896 3077 5313 8157 11812 16001 20962 25152 29088 31029 31520 30207 28102 24762 20670 16550 12792 9377 6651 4456 2783 1803 1090 655 354 169 85 38 10 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 497 937 1689 2973 5029 8225 12806 19083 27650 37772 49317 61141 72503 80963 86249 86738 83429 76661 67808 57154 45940 35639 26570 18965 12938 8405 5279 3295 1959 1097 569 269 126 48 12 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 497 937 1689 2973 5029 8225 12806 19083 27650 37772 49317 61141 72503 80963 86249 86738 83429 76661 67808 57154 45940 35639 26570 18965 12938 8405 5279 3295 1959 1097 569 269 126 48 12 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.96078 1.90543 1.85059 1.82712 1.77228 1.73852 1.68231 1.63822 1.61018 1.55823 1.5216 1.47927 1.44754 1.4058 1.37595 1.34293 1.31958 1.29668 1.27855 1.25731 1.23704 1.22571 1.20991 1.19522 1.17664 1.1637 1.15824 1.15135 1.14173 1.11639 1.11577 1.0949 1.09665 1.05556 1.04167 1 1

tree_stats_height=45
tree_stats_numInternals=653724
tree_stats_numLeaves=346523
tree_stats_numNodes=1000247
tree_stats_numKeys=1000247

tree_stats_avgDegreeInternal=1.53008
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34644
tree_stats_avgKeyDepth=23.9357

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 48960 95424 179904 324288 570816 965568 1579200 2458752 3663936 5308800 7252224 9468864 11739072 13920576 15544896 16559808 16653696 16018368 14718912 13019136 10973568 8820480 6842688 5101440 3641280 2484096 1613760 1013568 632640 376128 210624 109248 51648 24192 9216 2304 384
tree_stats_sizeInBytes=192047424

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:63 2:1648 4:55994 8:371459 16:50733 32:1
    [2^00, 2^01]: 63
    (2^01, 2^02]: 1648
    (2^02, 2^03]: 55994
    (2^03, 2^04]: 371459
    (2^04, 2^05]: 50733
    (2^05, 2^06]: 1
sum_limbo_reclamation_event_size_total=5424441
count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9997 9998 9998 9997 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9997
count_limbo_reclamation_event_size_total=479898
sum_limbo_reclamation_event_count_total=989328
first_thread_announced_epoch_by_thread=44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500 44500
sum_num_inserts_by_thread=456086 458511 476544 463386 474059 456604 458500 472293 466470 480952 461958 458694 458859 474146 465790 465178 464051 461987 469982 470967 464158 465454 460961 466884 453773 460803 476666 463880 462778 467034 472153 473518 479863 467089 472852 469692 460787 473365 466924 467757 463913 473163 457740 459419 464789 476667 461022 456368
sum_num_inserts_total=22374489
sum_num_deletes_by_thread=457360 458827 475094 463894 474426 455425 459659 472893 467351 480000 461756 458999 460366 472670 465973 465964 463690 460182 468578 473275 465052 465612 461129 467019 455634 460783 475442 464011 462802 468687 472391 473667 479590 468283 473954 469789 460219 474204 466142 466469 463791 471503 456323 459613 463190 475859 460409 456805
sum_num_deletes_total=22374754
sum_num_searches_by_thread=8208203 8267271 8557192 8351932 8526185 8213491 8267718 8509024 8403469 8632558 8311674 8263327 8277167 8522245 8385801 8390335 8350341 8298780 8437904 8497902 8371350 8383921 8289191 8424517 8190938 8278696 8565940 8359571 8321198 8431099 8474380 8526736 8633096 8428669 8526319 8466018 8292670 8530801 8396836 8402779 8343425 8497312 8216998 8276994 8351678 8581419 8299146 8233108
sum_num_searches_total=402767324
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=9121649 9184609 9508830 9279212 9474670 9125520 9185877 9454210 9337290 9593510 9235388 9181020 9196392 9469061 9317564 9321477 9278082 9220949 9376464 9442144 9300560 9314987 9211281 9358420 9100345 9200282 9518048 9287462 9246778 9366820 9418924 9473921 9592549 9364041 9473125 9405499 9213676 9478370 9329902 9337005 9271129 9441978 9131061 9196026 9279657 9533945 9220577 9146281
average_num_operations_total=9323261
stdev_num_operations_total=19244
sum_num_operations_total=447516567
min_num_operations_total=9100345
max_num_operations_total=9593510
first_time_thread_terminate_by_thread=10000138 10000137 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000137 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138 10000138
min_time_thread_terminate_total=10000137
max_time_thread_terminate_total=10000138
first_time_thread_start_by_thread=3 3 3 3 4 4 3 3 3 4 4 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 4 3 3 3 4 4 3 3 4 3 3 3 3 3 3 3 3 3 3
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=999853361553
threads_final_size=1000247
final_keysum=999853361553
final_size=1000247
validate_result=success
Validation OK.
Structural validation OK.

total_find=402767324
total_rq=0
total_inserts=22374489
total_deletes=22374754
total_updates=44749243
total_queries=402767324
total_ops=447516567
find_throughput=40276732
rq_throughput=0
update_throughput=4474924
query_throughput=40276732
total_throughput=44751656


total find                    : 402767324
total rq                      : 0
total inserts                 : 22374489
total deletes                 : 22374754
total updates                 : 44749243
total queries                 : 402767324
total ops                     : 447516567
find throughput               : 40276732
rq throughput                 : 0
update throughput             : 4474924
query throughput              : 40276732
total throughput              : 44751656

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=12.8271
PAPI_L3_TCM=2.06778
PAPI_TOT_CYC=2990.57
PAPI_TOT_INS=372.907
garbage=201317283463238
total_execution_walltime=11.397s
REALTIME_MAIN_END_PERF_FORMAT=6984993.50372021
[time_cmd_output] time_elapsed_sec=11.53, faults_major=0, faults_minor=124050, mem_maxresident_kb=498148, user_cputime=558.58, sys_cputime=2.51, percent_cpu=4864%
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=1