__step=000618
__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 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000618.txt
__path_data=data/data000618.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6986305.473037371
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=200000
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.004s
prefilling_size=100221 prefilling_elapsed_millis=1.001s
finished prefilling to size 100261 for expected size 100000 keysum=10008583984, performing 68313004 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=100261
pref_millis=1102
prefill_elapsed_ms=1124
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=3164

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6986306.774879133
REALTIME_END_PERF_FORMAT=6986316.775026139

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

tree_stats_computeWalltime=0.05s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 63 125 237 430 735 1182 1831 2646 3536 4430 5292 6000 6303 6270 5889 5165 4281 3399 2607 1834 1233 813 519 286 114 47 17 8 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 1 0 6 27 67 165 286 527 967 1470 1912 2457 2997 3349 3552 3545 3195 2680 2179 1750 1261 865 544 388 242 90 44 12 8 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 125 243 457 802 1347 2117 3173 4503 5900 7204 8457 9300 9619 9441 8710 7476 6079 4786 3584 2494 1678 1063 674 356 137 61 20 9 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 125 243 457 802 1347 2117 3173 4503 5900 7204 8457 9300 9619 9441 8710 7476 6079 4786 3584 2494 1678 1063 674 356 137 61 20 9 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.96875 1.944 1.90535 1.814 1.76309 1.69414 1.63392 1.58525 1.52498 1.47017 1.43934 1.39021 1.35656 1.32966 1.2988 1.26533 1.2405 1.22816 1.20414 1.18415 1.17843 1.14899 1.14581 1.10386 1.06461 1.10219 1.04918 1.05 1 1

tree_stats_height=36
tree_stats_numInternals=65356
tree_stats_numLeaves=34587
tree_stats_numNodes=99943
tree_stats_numKeys=99943

tree_stats_avgDegreeInternal=1.52921
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34607
tree_stats_avgKeyDepth=19.1672

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24000 46656 87744 153984 258624 406464 609216 864576 1132800 1383168 1623744 1785600 1846848 1812672 1672320 1435392 1167168 918912 688128 478848 322176 204096 129408 68352 26304 11712 3840 1728 192
tree_stats_sizeInBytes=19189056

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:68 2:1517 4:50913 8:371101 16:56276 32:23
    [2^00, 2^01]: 68
    (2^01, 2^02]: 1517
    (2^02, 2^03]: 50913
    (2^03, 2^04]: 371101
    (2^04, 2^05]: 56276
    (2^05, 2^06]: 23
sum_limbo_reclamation_event_size_total=5518320
count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9997 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9997
count_limbo_reclamation_event_size_total=479898
sum_limbo_reclamation_event_count_total=2028719
first_thread_announced_epoch_by_thread=90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858 90858
sum_num_inserts_by_thread=957581 965696 982447 971368 979923 954719 961599 966678 991663 979809 982519 960953 971627 981193 972144 974899 986394 970533 965309 982024 977109 975402 969476 962717 972102 964569 986665 974019 970723 960708 966508 967566 990442 978124 982357 965700 963914 984243 978162 976915 972036 981844 960085 975976 971882 988905 980216 975990
sum_num_inserts_total=46733433
sum_num_deletes_by_thread=957548 965099 984840 967520 980730 955670 962147 968560 990846 979011 982219 961199 970177 980196 970350 975913 987438 971049 964657 983811 976755 974234 969762 962075 972540 962540 987137 974847 968890 961763 966495 971451 989811 979653 983010 964242 966084 983298 976646 975991 973076 981259 959464 978388 972771 988781 981900 974509
sum_num_deletes_total=46736352
sum_num_searches_by_thread=17225605 17375751 17701456 17466990 17653015 17193522 17315619 17424394 17835833 17618393 17685389 17278776 17481997 17674415 17472769 17570018 17754417 17476795 17356963 17667815 17569264 17541279 17452718 17319219 17488111 17346425 17775426 17507120 17455717 17299704 17373891 17449133 17813473 17632251 17686191 17359371 17375101 17713467 17610017 17592763 17492407 17685789 17284508 17579279 17499758 17784814 17688692 17558491
sum_num_searches_total=841164311
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=19140734 19306546 19668743 19405878 19613668 19103911 19239365 19359632 19818342 19577213 19650127 19200928 19423801 19635804 19415263 19520830 19728249 19418377 19286929 19633650 19523128 19490915 19391956 19244011 19432753 19273534 19749228 19455986 19395330 19222175 19306894 19388150 19793726 19590028 19651558 19289313 19305099 19681008 19564825 19545669 19437519 19648892 19204057 19533643 19444411 19762500 19650808 19508990
average_num_operations_total=19471543
stdev_num_operations_total=26740
sum_num_operations_total=934634096
min_num_operations_total=19103911
max_num_operations_total=19818342
first_time_thread_terminate_by_thread=10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000145 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000145 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000146
min_time_thread_terminate_total=10000145
max_time_thread_terminate_total=10000146
first_time_thread_start_by_thread=6 5 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 5 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=5
max_time_thread_start_total=6
sum_duration_all_ops_total=

threads_final_keysum=10008668573
threads_final_size=99943
final_keysum=10008668573
final_size=99943
validate_result=success
Validation OK.
Structural validation OK.

total_find=841164311
total_rq=0
total_inserts=46733433
total_deletes=46736352
total_updates=93469785
total_queries=841164311
total_ops=934634096
find_throughput=84116431
rq_throughput=0
update_throughput=9346978
query_throughput=84116431
total_throughput=93463409


total find                    : 841164311
total rq                      : 0
total inserts                 : 46733433
total deletes                 : 46736352
total updates                 : 93469785
total queries                 : 841164311
total ops                     : 934634096
find throughput               : 84116431
rq throughput                 : 0
update throughput             : 9346978
query throughput              : 84116431
total throughput              : 93463409

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=4.54345
PAPI_L3_TCM=0.00139593
PAPI_TOT_CYC=1425.4
PAPI_TOT_INS=334.727
garbage=42052068906718
total_execution_walltime=11.429s
REALTIME_MAIN_END_PERF_FORMAT=6986316.918759594
[time_cmd_output] time_elapsed_sec=11.57, faults_major=0, faults_minor=81399, mem_maxresident_kb=327176, user_cputime=559.41, sys_cputime=2.37, percent_cpu=4853%
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