__step=000506
__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=data000506.txt
__path_data=data/data000506.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6984993.232650678
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.006s
prefilling_size=999745 prefilling_elapsed_millis=1.001s
finished prefilling to size 999771 for expected size 1000000 keysum=999251980610, performing 35400887 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=999771
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=1630

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6984994.558587910
REALTIME_END_PERF_FORMAT=6985004.558730356

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

tree_stats_computeWalltime=0.041s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 251 493 947 1752 3107 5267 8476 12779 18141 24259 30812 37407 42905 47501 50624 51317 50574 48042 44198 39243 33579 27237 21691 16742 12528 8911 6059 4080 2547 1489 847 466 226 115 57 36 19 8 3 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 8 20 80 219 518 1168 2271 4033 6511 9478 13010 16953 20273 23523 26534 27610 28048 27381 25869 23471 20958 17153 13844 10848 8432 6117 4161 2878 1897 1088 622 372 179 93 38 25 16 7 3
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 127 252 501 967 1832 3326 5785 9644 15050 22174 30770 40290 50417 59858 67774 74147 77851 78184 76090 71579 65112 57050 48195 38844 30586 23376 17343 12176 8241 5425 3386 1935 1088 598 294 150 74 44 24 10 3
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 127 252 501 967 1832 3326 5785 9644 15050 22174 30770 40290 50417 59858 67774 74147 77851 78184 76090 71579 65112 57050 48195 38844 30586 23376 17343 12176 8241 5425 3386 1935 1088 598 294 150 74 44 24 10 3
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.98438 1.98425 1.99206 1.94611 1.9152 1.85917 1.80517 1.75661 1.68167 1.62425 1.56954 1.52099 1.4866 1.44531 1.41547 1.39316 1.3672 1.34511 1.32636 1.30933 1.29218 1.27348 1.2562 1.24083 1.22899 1.2169 1.20598 1.18826 1.1792 1.16321 1.15465 1.13172 1.12455 1.12132 1.11371 1.11905 1.11333 1.10811 1.11364 1.08333 1 1

tree_stats_height=48
tree_stats_numInternals=654989
tree_stats_numLeaves=345710
tree_stats_numNodes=1000699
tree_stats_numKeys=1000699

tree_stats_avgDegreeInternal=1.52781
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34547
tree_stats_avgKeyDepth=24.1148

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24384 48384 96192 185664 351744 638592 1110720 1851648 2889600 4257408 5907840 7735680 9680064 11492736 13012608 14236224 14947392 15011328 14609280 13743168 12501504 10953600 9253440 7458048 5872512 4488192 3329856 2337792 1582272 1041600 650112 371520 208896 114816 56448 28800 14208 8448 4608 1920 576
tree_stats_sizeInBytes=192134208

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:50 2:1510 4:53696 8:371520 16:53096 32:29
    [2^00, 2^01]: 50
    (2^01, 2^02]: 1510
    (2^02, 2^03]: 53696
    (2^03, 2^04]: 371520
    (2^04, 2^05]: 53096
    (2^05, 2^06]: 29
sum_limbo_reclamation_event_size_total=5465530
count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9997 9998 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 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998
count_limbo_reclamation_event_size_total=479901
sum_limbo_reclamation_event_count_total=986736
first_thread_announced_epoch_by_thread=44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374 44374
sum_num_inserts_by_thread=461442 464524 472348 472932 471019 464290 464143 466187 472042 476822 466128 463518 467339 467068 470575 471762 470100 465759 465429 468213 468889 474324 469446 465690 462302 468470 471590 473719 471593 462950 463926 470531 478179 474861 471970 467453 465324 466265 472028 475432 468377 469226 464147 463886 466825 469378 470562 463324
sum_num_inserts_total=22492307
sum_num_deletes_by_thread=460442 465576 473696 472891 470973 464314 464589 466324 472425 477201 466731 464874 467838 465807 470746 470973 469242 464636 464976 467886 469584 474809 469644 466121 461599 469163 468941 473892 471230 461444 465567 471381 476209 473024 470761 468399 465433 465830 472460 474845 469820 469577 466090 463371 468146 471011 470559 461635
sum_num_deletes_total=22492685
sum_num_searches_by_thread=8309396 8366678 8522742 8502758 8476910 8366770 8360075 8400474 8504249 8587869 8406691 8350392 8430193 8400151 8474909 8482049 8449527 8376563 8391484 8439388 8445658 8543125 8460436 8378792 8316330 8439822 8452046 8529413 8490799 8318729 8364254 8478551 8584333 8521920 8489439 8427264 8381532 8401521 8494627 8557970 8432213 8443127 8369833 8357570 8420831 8470613 8458161 8317476
sum_num_searches_total=404945653
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=9231280 9296778 9468786 9448581 9418902 9295374 9288807 9332985 9448716 9541892 9339550 9278784 9365370 9333026 9416230 9424784 9388869 9306958 9321889 9375487 9384131 9492258 9399526 9310603 9240231 9377455 9392577 9477024 9433622 9243123 9293747 9420463 9538721 9469805 9432170 9363116 9312289 9333616 9439115 9508247 9370410 9381930 9300070 9284827 9355802 9411002 9399282 9242435
average_num_operations_total=9373555
stdev_num_operations_total=11519
sum_num_operations_total=449930645
min_num_operations_total=9231280
max_num_operations_total=9541892
first_time_thread_terminate_by_thread=10000141 10000141 10000141 10000141 10000142 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141 10000141
min_time_thread_terminate_total=10000141
max_time_thread_terminate_total=10000142
first_time_thread_start_by_thread=3 3 3 3 3 2 2 2 3 3 3 3 3 3 3 2 3 3 3 2 3 3 2 2 3 3 3 3 3 2 2 2 3 3 3 3 3 3 3 2 3 3 3 2 3 3 2 2
min_time_thread_start_total=2
max_time_thread_start_total=3
sum_duration_all_ops_total=

threads_final_keysum=1000609850320
threads_final_size=1000699
final_keysum=1000609850320
final_size=1000699
validate_result=success
Validation OK.
Structural validation OK.

total_find=404945653
total_rq=0
total_inserts=22492307
total_deletes=22492685
total_updates=44984992
total_queries=404945653
total_ops=449930645
find_throughput=40494565
rq_throughput=0
update_throughput=4498499
query_throughput=40494565
total_throughput=44993064


total find                    : 404945653
total rq                      : 0
total inserts                 : 22492307
total deletes                 : 22492685
total updates                 : 44984992
total queries                 : 404945653
total ops                     : 449930645
find throughput               : 40494565
rq throughput                 : 0
update throughput             : 4498499
query throughput              : 40494565
total throughput              : 44993064

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=12.622
PAPI_L3_TCM=2.06464
PAPI_TOT_CYC=2974.25
PAPI_TOT_INS=374.207
garbage=202446188798855
total_execution_walltime=11.479s
REALTIME_MAIN_END_PERF_FORMAT=6985004.712903325
[time_cmd_output] time_elapsed_sec=11.60, faults_major=0, faults_minor=123954, mem_maxresident_kb=497932, user_cputime=564.43, sys_cputime=1.99, percent_cpu=4879%
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