__step=000396
__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 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000396.txt
__path_data=data/data000396.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983617.513799596
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=20000000
PREFILL_THREADS=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.008s
prefilling_size=8667032 prefilling_elapsed_millis=1.001s
prefilling_size=8875964 prefilling_elapsed_millis=1.101s
prefilling_size=9051344 prefilling_elapsed_millis=1.201s
prefilling_size=9198376 prefilling_elapsed_millis=1.301s
prefilling_size=9321533 prefilling_elapsed_millis=1.402s
prefilling_size=9425147 prefilling_elapsed_millis=1.502s
prefilling_size=9512401 prefilling_elapsed_millis=1.602s
prefilling_size=9584237 prefilling_elapsed_millis=1.702s
prefilling_size=9646761 prefilling_elapsed_millis=1.802s
prefilling_size=9700984 prefilling_elapsed_millis=1.903s
prefilling_size=9742909 prefilling_elapsed_millis=2.003s
prefilling_size=9779747 prefilling_elapsed_millis=2.103s
prefilling_size=9811140 prefilling_elapsed_millis=2.203s
finished prefilling to size 9811202 for expected size 10000000 keysum=98106037825586, performing 83702585 updates; total_prefilling_elapsed_ms=2305 ms)
pref_size=9811202
pref_millis=2305
prefill_elapsed_ms=2306
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=959

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6983619.987296767
REALTIME_END_PERF_FORMAT=6983629.987443298

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

tree_stats_computeWalltime=0.19s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 507 999 1910 3621 6613 11656 19806 32416 50468 74966 106000 142990 184127 227969 271487 312213 347319 375787 396249 409241 412887 406204 390930 366403 337352 303893 267608 231122 194872 160119 128352 100009 76030 56416 40303 28003 18389 11719 7257 4409 2618 1499 849 474 250 114 59 31 11 3 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 3 7 52 107 346 852 1948 3974 7808 14194 23987 37166 54788 75295 98486 123168 147801 169652 188439 202917 214721 221841 222282 218698 207794 194738 178543 159572 140101 120117 100111 81914 64659 49805 37682 27240 19496 13022 8304 5217 3210 1949 1122 640 357 208 85 44 24 10 2 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 510 1006 1962 3728 6959 12508 21754 36390 58276 89160 129987 180156 238915 303264 369973 435381 495120 545439 584688 612158 627608 628045 613212 585101 545146 498631 446151 390694 334973 280236 228463 181923 140689 106221 77985 55243 37885 24741 15561 9626 5828 3448 1971 1114 607 322 144 75 35 13 3 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 510 1006 1962 3728 6959 12508 21754 36390 58276 89160 129987 180156 238915 303264 369973 435381 495120 545439 584688 612158 627608 628045 613212 585101 545146 498631 446151 390694 334973 280236 228463 181923 140689 106221 77985 55243 37885 24741 15561 9626 5828 3448 1971 1114 607 322 144 75 35 13 3 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99219 1.97843 1.95726 1.92661 1.89539 1.8471 1.80732 1.76234 1.71063 1.66394 1.6171 1.57049 1.53246 1.49866 1.46825 1.44299 1.42011 1.40015 1.383 1.36927 1.35672 1.34282 1.32961 1.31665 1.30549 1.29585 1.2853 1.27588 1.26581 1.25484 1.24388 1.23448 1.22361 1.21459 1.20306 1.19158 1.17888 1.16767 1.15529 1.15224 1.14741 1.14242 1.13689 1.13445 1.11939 1.11862 1.09317 1.11111 1.05333 1.05714 1 1 1

tree_stats_height=61
tree_stats_numInternals=6525041
tree_stats_numLeaves=3444499
tree_stats_numNodes=9969540
tree_stats_numKeys=9969540

tree_stats_avgDegreeInternal=1.52789
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.3455
tree_stats_avgKeyDepth=30.0335

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 49152 97920 193152 376704 715776 1336128 2401536 4176768 6986880 11188992 17118720 24957504 34589952 45871680 58226688 71034816 83593152 95063040 104724288 112260096 117534336 120500736 120584640 117736704 112339392 104668032 95737152 85660992 75013248 64314816 53805312 43864896 34929216 27012288 20394432 14973120 10606656 7273920 4750272 2987712 1848192 1118976 662016 378432 213888 116544 61824 27648 14400 6720 2496 576 192
tree_stats_sizeInBytes=1914151680

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:2 4:45 8:23429 16:425825 32:18800 64:91
    (2^01, 2^02]: 2
    (2^02, 2^03]: 45
    (2^03, 2^04]: 23429
    (2^04, 2^05]: 425825
    (2^05, 2^06]: 18800
    (2^06, 2^07]: 91
sum_limbo_reclamation_event_size_total=10756006
count_limbo_reclamation_event_size_by_thread=4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877 4877
count_limbo_reclamation_event_size_total=468192
sum_limbo_reclamation_event_count_total=468384
first_thread_announced_epoch_by_thread=11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676 11676
sum_num_inserts_by_thread=222001 226633 226034 228351 225436 223389 223133 225654 230182 228727 226693 222679 224144 227817 227663 227703 227455 223800 224788 222845 224933 227167 225650 223936 221890 226821 226172 228608 227716 224083 226364 225465 227481 230669 226529 222435 223961 226112 227359 229742 227765 223998 223967 225322 227646 227559 226152 221587 223428 223755 226983 226880 224004 223421 222939 225153 229924 228742 226094 224309 226092 227610 227926 229430 225729 224115 223902 225072 227589 227211 224733 225252 222949 224514 226315 228824 223769 225353 226294 224826 229249 228409 225113 223338 224419 225742 227487 229401 225113 222036 223781 225395 229572 227296 225413 223538
sum_num_inserts_total=21680655
sum_num_deletes_by_thread=222098 225807 225792 228369 225553 223896 223923 224299 229050 228883 226332 222578 223379 227219 227587 226991 228181 224364 224140 224099 225415 227303 225339 223467 222133 225623 225652 228276 227811 223411 225473 224366 228260 229557 226300 222694 224217 227474 228189 229496 227642 223523 223567 225607 228416 227613 225822 221392 222762 224440 226631 227098 224855 223443 223633 225299 230357 229270 225808 224486 226355 228608 227496 229654 225042 224031 224079 225894 226779 227432 224921 224783 222758 224080 226550 228479 224657 225633 225831 224723 230199 228322 226412 223599 224899 226808 227809 229779 225604 222517 223629 225822 229359 227405 224720 223207
sum_num_deletes_total=21682535
sum_num_searches_by_thread=4000322 4070468 4076775 4110213 4048964 4034803 4021412 4044013 4141298 4107333 4080735 4010156 4024748 4090684 4091569 4090898 4098769 4023391 4029677 4024327 4066741 4085109 4065023 4025280 3996466 4070047 4078208 4112314 4089499 4035196 4063789 4044192 4105178 4144598 4083293 4015737 4027500 4093608 4094511 4131083 4099769 4024081 4033580 4061920 4104109 4084123 4063620 3982611 4010668 4036020 4080261 4078826 4033262 4018964 4026747 4051353 4149089 4108657 4067469 4027495 4080379 4105392 4101170 4136566 4058629 4042205 4028654 4053900 4086104 4084767 4047939 4053907 4010348 4034463 4081756 4118419 4034233 4055454 4056688 4044279 4148144 4114673 4066847 4031233 4040669 4067247 4095224 4135758 4055402 4002734 4030104 4060018 4131354 4089762 4052144 4016282
sum_num_searches_total=390243398
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4444421 4522908 4528601 4566933 4499953 4482088 4468468 4493966 4600530 4564943 4533760 4455413 4472271 4545720 4546819 4545592 4554405 4471555 4478605 4471271 4517089 4539579 4516012 4472683 4440489 4522491 4530032 4569198 4545026 4482690 4515626 4494023 4560919 4604824 4536122 4460866 4475678 4547194 4550059 4590321 4555176 4471602 4481114 4512849 4560171 4539295 4515594 4425590 4456858 4484215 4533875 4532804 4482121 4465828 4473319 4501805 4609370 4566669 4519371 4476290 4532826 4561610 4556592 4595650 4509400 4490351 4476635 4504866 4540472 4539410 4497593 4503942 4456055 4483057 4534621 4575722 4482659 4506440 4508813 4493828 4607592 4571404 4518372 4478170 4489987 4519797 4550520 4594938 4506119 4447287 4477514 4511235 4590285 4544463 4502277 4463027
average_num_operations_total=4516735
stdev_num_operations_total=4387
sum_num_operations_total=433606588
min_num_operations_total=4425590
max_num_operations_total=4609370
first_time_thread_terminate_by_thread=10000147 10000146 10000148 10000147 10000147 10000147 10000149 10000147 10000148 10000146 10000148 10000146 10000148 10000147 10000149 10000147 10000147 10000148 10000147 10000149 10000148 10000147 10000149 10000147 10000147 10000146 10000146 10000147 10000147 10000149 10000149 10000147 10000148 10000146 10000148 10000149 10000147 10000147 10000149 10000147 10000147 10000148 10000149 10000147 10000148 10000147 10000149 10000147 10000149 10000149 10000146 10000146 10000148 10000149 10000146 10000146 10000148 10000146 10000146 10000146 10000148 10000148 10000147 10000146 10000146 10000146 10000146 10000146 10000148 10000146 10000146 10000146 10000149 10000149 10000148 10000149 10000148 10000146 10000146 10000147 10000148 10000146 10000146 10000146 10000148 10000146 10000147 10000146 10000146 10000146 10000146 10000146 10000146 10000146 10000147 10000146
min_time_thread_terminate_total=10000146
max_time_thread_terminate_total=10000149
first_time_thread_start_by_thread=3 3 2 3 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 3 3 3 3 2 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 2 3 2 3 3 2 3 3 3 3 3 2 3 3 3 3 2 2 2 3 2 2 2 2 2 3 2 3 3 2 3 3 3 3 3 2 3 3 3 3 2 2 2 3 2 2 2 2
min_time_thread_start_total=2
max_time_thread_start_total=3
sum_duration_all_ops_total=

threads_final_keysum=99680909947587
threads_final_size=9969540
final_keysum=99680909947587
final_size=9969540
validate_result=success
Validation OK.
Structural validation OK.

total_find=390243398
total_rq=0
total_inserts=21680655
total_deletes=21682535
total_updates=43363190
total_queries=390243398
total_ops=433606588
find_throughput=39024339
rq_throughput=0
update_throughput=4336319
query_throughput=39024339
total_throughput=43360658


total find                    : 390243398
total rq                      : 0
total inserts                 : 21680655
total deletes                 : 21682535
total updates                 : 43363190
total queries                 : 390243398
total ops                     : 433606588
find throughput               : 39024339
rq throughput                 : 0
update throughput             : 4336319
query throughput              : 39024339
total throughput              : 43360658

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=32.9001
PAPI_L3_TCM=8.76713
PAPI_TOT_CYC=6170.31
PAPI_TOT_INS=421.481
garbage=1936333445117279
total_execution_walltime=12.751s
REALTIME_MAIN_END_PERF_FORMAT=6983630.265920481
[time_cmd_output] time_elapsed_sec=13.02, faults_major=0, faults_minor=551440, mem_maxresident_kb=2203424, user_cputime=1201.18, sys_cputime=6.07, percent_cpu=9271%
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