__step=000953
__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 50.0 50.0 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000953.txt
__path_data=data/data000953.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6990425.64704051
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=50
DEL_FRAC=50
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=50.0 50.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=100124 prefilling_elapsed_millis=1.001s
finished prefilling to size 100157 for expected size 100000 keysum=10013594774, performing 68751154 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=100157
pref_millis=1102
prefill_elapsed_ms=1123
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=3183

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6990426.363046898
REALTIME_END_PERF_FORMAT=6990436.363183743

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

tree_stats_computeWalltime=0.037s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 495 906 1586 2450 3561 4779 6010 6835 7254 7107 6290 5183 3983 2916 1988 1255 807 449 238 131 78 50 38 39 37 34 34 31 32 26 22 17 19 19 18 21 17 17 11 5 3 3 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 4 45 137 404 730 1269 1894 2750 3482 3922 4181 3903 3390 2641 2001 1444 872 595 340 184 100 49 31 15 20 22 20 19 14 18 16 17 8 9 14 8 14 9 17 10 3 2 2 3
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 499 951 1723 2854 4291 6048 7904 9585 10736 11029 10471 9086 7373 5557 3989 2699 1679 1044 578 315 178 99 69 54 57 56 54 50 46 44 38 34 27 28 32 29 31 26 28 15 6 5 4 3
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 499 951 1723 2854 4291 6048 7904 9585 10736 11029 10471 9086 7373 5557 3989 2699 1679 1044 578 315 178 99 69 54 57 56 54 50 46 44 38 34 27 28 32 29 31 26 28 15 6 5 4 3
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.95312 1.91383 1.8591 1.73593 1.64506 1.57959 1.5167 1.4523 1.40699 1.35162 1.30501 1.26702 1.24103 1.21348 1.19309 1.17824 1.1571 1.14116 1.12356 1.13322 1.14921 1.11798 1.19192 1.23188 1.33333 1.33333 1.35714 1.2963 1.3 1.26087 1.27273 1.31579 1.29412 1.33333 1.46429 1.34375 1.34483 1.29032 1.42308 1.14286 1.06667 1.33333 1.2 1.25 1

tree_stats_height=53
tree_stats_numInternals=65306
tree_stats_numLeaves=34629
tree_stats_numNodes=99935
tree_stats_numKeys=99935

tree_stats_avgDegreeInternal=1.53026
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34652
tree_stats_avgKeyDepth=18.0935

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 49152 95808 182592 330816 547968 823872 1161216 1517568 1840320 2061312 2117568 2010432 1744512 1415616 1066944 765888 518208 322368 200448 110976 60480 34176 19008 13248 10368 10944 10752 10368 9600 8832 8448 7296 6528 5184 5376 6144 5568 5952 4992 5376 2880 1152 960 768 576
tree_stats_sizeInBytes=19187520

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:6 2:4 4:5 8:12 16:18 32:584 64:451449 128:27699 256:46 512:45
    [2^00, 2^01]: 6
    (2^01, 2^02]: 4
    (2^02, 2^03]: 5
    (2^03, 2^04]: 12
    (2^04, 2^05]: 18
    (2^05, 2^06]: 584
    (2^06, 2^07]: 451449
    (2^07, 2^08]: 27699
    (2^08, 2^09]: 46
    (2^09, 2^10]: 45
sum_limbo_reclamation_event_size_total=53928302
count_limbo_reclamation_event_size_by_thread=9998 9997 9997 9997 9997 9997 9996 9997 9996 9998 9998 9998 9996 9998 9997 9997 9998 9997 9998 9996 9998 9996 9996 9998 9998 9996 9997 9998 9997 9997 9998 9998 9997 9998 9998 9997 9996 9998 9998 9997 9997 9997 9997 9998 9997 9998 9997 9998
count_limbo_reclamation_event_size_total=479868
sum_limbo_reclamation_event_count_total=1515295
first_thread_announced_epoch_by_thread=69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504 69504
sum_num_inserts_by_thread=7019332 7054941 7105289 7099939 7079593 7005332 7025412 7061076 7156035 7206034 7110615 7034506 7035265 7107074 7111249 7147960 7141108 7081002 7044401 7042224 7153380 7140372 7073469 7060912 7014157 7057184 7118136 7142137 7092912 7020387 7059215 7114744 7171024 7211529 7111918 7033400 7047607 7114845 7180646 7162577 7115996 7076931 7001945 7086876 7104361 7178329 7112766 7066842
sum_num_inserts_total=340492984
sum_num_deletes_by_thread=7014347 7058828 7107746 7104682 7086669 7010844 7023609 7056214 7156550 7206323 7108696 7033281 7038358 7102208 7108272 7153103 7140915 7081095 7049583 7044184 7153700 7148954 7070747 7060215 7019756 7056144 7124693 7140927 7094254 7014732 7059471 7105948 7172605 7211834 7114702 7036138 7052417 7117042 7178235 7162638 7105454 7081412 6999419 7080549 7101122 7177801 7107404 7072665
sum_num_deletes_total=340506485
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=14033679 14113769 14213035 14204621 14166262 14016176 14049021 14117290 14312585 14412357 14219311 14067787 14073623 14209282 14219521 14301063 14282023 14162097 14093984 14086408 14307080 14289326 14144216 14121127 14033913 14113328 14242829 14283064 14187166 14035119 14118686 14220692 14343629 14423363 14226620 14069538 14100024 14231887 14358881 14325215 14221450 14158343 14001364 14167425 14205483 14356130 14220170 14139507
average_num_operations_total=14187488
stdev_num_operations_total=15711
sum_num_operations_total=680999469
min_num_operations_total=14001364
max_num_operations_total=14423363
first_time_thread_terminate_by_thread=10000134 10000135 10000136 10000134 10000134 10000135 10000135 10000134 10000135 10000135 10000135 10000134 10000135 10000134 10000135 10000135 10000134 10000134 10000135 10000134 10000134 10000134 10000135 10000134 10000134 10000138 10000135 10000135 10000134 10000135 10000135 10000134 10000135 10000135 10000134 10000134 10000135 10000134 10000135 10000135 10000134 10000134 10000135 10000134 10000134 10000134 10000135 10000134
min_time_thread_terminate_total=10000134
max_time_thread_terminate_total=10000138
first_time_thread_start_by_thread=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 4 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=9988779855
threads_final_size=99935
final_keysum=9988779855
final_size=99935
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=340492984
total_deletes=340506485
total_updates=680999469
total_queries=0
total_ops=680999469
find_throughput=0
rq_throughput=0
update_throughput=68099946
query_throughput=0
total_throughput=68099946


total find                    : 0
total rq                      : 0
total inserts                 : 340492984
total deletes                 : 340506485
total updates                 : 680999469
total queries                 : 0
total ops                     : 680999469
find throughput               : 0
rq throughput                 : 0
update throughput             : 68099946
query throughput              : 0
total throughput              : 68099946

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=10.2443
PAPI_L3_TCM=0.00486389
PAPI_TOT_CYC=1957.81
PAPI_TOT_INS=475.403
garbage=0
total_execution_walltime=11.429s
REALTIME_MAIN_END_PERF_FORMAT=6990436.495043563
[time_cmd_output] time_elapsed_sec=11.54, faults_major=0, faults_minor=82861, mem_maxresident_kb=332944, user_cputime=558.84, sys_cputime=3.50, percent_cpu=4871%
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