__step=000074
__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" ./sigouin_int_bst_kcas_validate.debra -nwork 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 0.5 0.5 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000074.txt
__path_data=data/data000074.txt
__hostname=nasus
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x560cca5f53c0
addrof(kcasDescriptors[1])=0x560cca5f5cc0
addrof(rdcssDescriptors[0])=0x560cca7153c0
addrof(rdcssDescriptors[1])=0x560cca715480
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=207731.948165976
binary=./sigouin_int_bst_kcas_validate.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=sigouin_int_bst_kcas_validate
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=0.5
DEL_FRAC=0.5
RQ=0
RQSIZE=0
MAXKEY=20000000
PREFILL_THREADS=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
RQ_THREADS=0
distribution=0
INS_DEL_FRAC=0.5 0.5
PREFILL_TYPE=PREFILL_HYBRID
PREFILL_HYBRID_MIN_MS=1000
PREFILL_HYBRID_MAX_MS=5000
sizes: node=56
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.022s
prefilling_size=7736584 prefilling_elapsed_millis=1s
prefilling_size=8004778 prefilling_elapsed_millis=1.101s
prefilling_size=8241388 prefilling_elapsed_millis=1.201s
prefilling_size=8448772 prefilling_elapsed_millis=1.301s
prefilling_size=8629678 prefilling_elapsed_millis=1.401s
prefilling_size=8787446 prefilling_elapsed_millis=1.501s
prefilling_size=8928297 prefilling_elapsed_millis=1.601s
prefilling_size=9051736 prefilling_elapsed_millis=1.701s
prefilling_size=9160331 prefilling_elapsed_millis=1.801s
prefilling_size=9258348 prefilling_elapsed_millis=1.902s
prefilling_size=9343246 prefilling_elapsed_millis=2.002s
prefilling_size=9416612 prefilling_elapsed_millis=2.102s
prefilling_size=9480697 prefilling_elapsed_millis=2.202s
prefilling_size=9539679 prefilling_elapsed_millis=2.302s
prefilling_size=9590633 prefilling_elapsed_millis=2.402s
prefilling_size=9636982 prefilling_elapsed_millis=2.502s
prefilling_size=9679610 prefilling_elapsed_millis=2.602s
prefilling_size=9716298 prefilling_elapsed_millis=2.703s
prefilling_size=9748385 prefilling_elapsed_millis=2.803s
prefilling_size=9778773 prefilling_elapsed_millis=2.903s
prefilling_size=9805370 prefilling_elapsed_millis=3.003s
finished prefilling to size 9805378 for expected size 10000000 keysum=98052492710690, performing 78902509 updates; total_prefilling_elapsed_ms=3104 ms)
pref_size=9805378
pref_millis=3104
prefill_elapsed_ms=3123
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=207735.107183928
REALTIME_END_PERF_FORMAT=207745.107277928

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

tree_stats_computeWalltime=0.396s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 254 505 982 1889 3600 6641 11824 20293 33686 53557 81445 118593 164635 217443 273058 327102 375123 412866 437523 448054 444580 428281 402861 370615 332765 292922 251718 211993 174734 140909 110892 85883 65204 47992 34364 23955 15838 10179 6485 3864 2133 1106 561 241 109 53 22 8 2 3 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 3 12 45 104 311 827 1828 3724 7513 13873 23733 38922 59704 86014 116398 147631 178038 204823 224522 236657 241601 237404 227472 213152 194312 174425 151636 129733 108253 88903 70654 55141 42709 31919 23160 16598 11058 7063 4643 2834 1633 832 458 190 83 42 15 7 0 2 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 508 994 1934 3704 6952 12651 22121 37410 61070 95318 142326 203557 277147 359072 443500 522754 590904 642346 672576 681237 669882 640265 598087 545917 487234 426143 363629 304467 249162 199795 156537 120345 90701 66283 47115 32436 21237 13548 8507 4967 2739 1393 699 299 136 64 23 9 3 3 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 508 994 1934 3704 6952 12651 22121 37410 61070 95318 142326 203557 277147 359072 443500 522754 590904 642346 672576 681237 669882 640265 598087 545917 487234 426143 363629 304467 249162 199795 156537 120345 90701 66283 47115 32436 21237 13548 8507 4967 2739 1393 699 299 136 64 23 9 3 3 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.99608 1.9626 1.95775 1.93847 1.90497 1.8645 1.81393 1.77379 1.732 1.68382 1.63871 1.59697 1.55273 1.51102 1.47467 1.44115 1.41278 1.38835 1.36593 1.3467 1.33072 1.31645 1.30491 1.2931 1.28295 1.27342 1.26261 1.25431 1.24445 1.23634 1.22846 1.22015 1.21187 1.20166 1.19237 1.18001 1.16645 1.15864 1.14925 1.12966 1.12201 1.10478 1.09907 1.08298 1.0903 1.08088 1.01562 1.04348 1.11111 1 1 1

tree_stats_height=60
tree_stats_numInternals=6449601
tree_stats_numLeaves=3380616
tree_stats_numNodes=9830217
tree_stats_numKeys=9830217

tree_stats_avgDegreeInternal=1.52416
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.3439
tree_stats_avgKeyDepth=28.9979


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:155416 2:175645 4:31083 8:183 16:2
    [2^00, 2^01]: 155416
    (2^01, 2^02]: 175645
    (2^02, 2^03]: 31083
    (2^03, 2^04]: 183
    (2^04, 2^05]: 2
sum_limbo_reclamation_event_size_total=703239
count_limbo_reclamation_event_size_by_thread=5686 5677 5638 5701 5670 5656 5664 5621 5605 5657 5640 5684 5689 5669 5697 5725 5648 5658 5668 5610 5602 5667 5703 5714 5629 5667 5665 5699 5681 5713 5642 5597 5656 5672 5610 5681 5636 5682 5598 5732 5688 5682 5679 5646 5653 5656 5643 5636 5631 5658 5641 5649 5673 5649 5726 5714 5644 5672 5633 5641 5628 5680 5643 5655
count_limbo_reclamation_event_size_total=362329
sum_limbo_reclamation_event_count_total=464768
first_thread_announced_epoch_by_thread=18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582 18582
sum_num_inserts_by_thread=22335 22238 22354 22163 22377 22139 22198 22326 22698 22362 22460 22286 22416 22336 22313 22194 22326 22226 22310 22466 22498 22389 22316 22261 22513 22454 22415 22583 22369 22463 22197 22214 22402 22648 22325 22329 22836 22662 22531 22379 22329 22317 22512 22380 22232 22495 22314 22031 22225 22281 22290 21925 22402 22597 22440 22362 22371 22776 22272 22400 22048 22571 22327 22537
sum_num_inserts_total=1431741
sum_num_deletes_by_thread=22447 22360 22397 22176 22286 22395 22464 22381 22067 22191 22320 22440 22435 22198 22360 22363 22276 22343 22417 22033 22180 22435 22484 22607 22275 22226 22327 22339 22678 22419 22207 22276 22536 22433 22161 22330 22124 22650 22367 22490 22554 22639 22543 22359 22449 22339 22355 22476 22345 22313 22175 22303 22558 22309 22444 22215 22578 22648 22580 22484 22042 22536 22484 22487
sum_num_deletes_total=1432108
sum_num_searches_by_thread=4440635 4444957 4435042 4401688 4428596 4437798 4422709 4422292 4415598 4432599 4423353 4425799 4435667 4421073 4412889 4411398 4430828 4419203 4416722 4408230 4418408 4416751 4407048 4446544 4432593 4426208 4428095 4430621 4441994 4432616 4402303 4405175 4445283 4447574 4431883 4430260 4439445 4469003 4453677 4442885 4449426 4450031 4448710 4435340 4443959 4432973 4409207 4407766 4398055 4413120 4398643 4389526 4449422 4445630 4423060 4422070 4442293 4441938 4431604 4405126 4404524 4436703 4435212 4444536
sum_num_searches_total=283394316
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4485417 4489555 4479793 4446027 4473259 4482332 4467371 4466999 4460363 4477152 4468133 4470525 4480518 4465607 4457562 4455955 4475430 4463772 4461449 4452729 4463086 4461575 4451848 4491412 4477381 4470888 4472837 4475543 4487041 4477498 4446707 4449665 4490221 4492655 4476369 4474919 4484405 4514315 4498575 4487754 4494309 4494987 4493765 4480079 4488640 4477807 4453876 4452273 4442625 4457714 4443108 4433754 4494382 4490536 4467944 4466647 4487242 4487362 4476456 4450010 4448614 4481810 4480023 4489560
average_num_operations_total=4472783
stdev_num_operations_total=2105
sum_num_operations_total=286258165
min_num_operations_total=4433754
max_num_operations_total=4514315
first_time_thread_terminate_by_thread=10000093 10000094 10000094 10000094 10000095 10000095 10000095 10000095 10000096 10000096 10000096 10000096 10000095 10000095 10000095 10000095 10000097 10000097 10000097 10000097 10000096 10000096 10000096 10000096 10000098 10000098 10000098 10000098 10000097 10000097 10000108 10000097 10000094 10000094 10000094 10000094 10000093 10000093 10000093 10000093 10000095 10000095 10000095 10000096 10000097 10000097 10000097 10000097 10000097 10000097 10000098 10000097 10000095 10000095 10000095 10000095 10000096 10000096 10000096 10000096 10000094 10000094 10000094 10000094
min_time_thread_terminate_total=10000093
max_time_thread_terminate_total=10000108
first_time_thread_start_by_thread=10 10 10 10 9 9 9 9 9 9 9 9 10 10 10 10 10 10 10 10 11 11 11 11 12 12 12 12 10 10 10 10 8 8 8 8 6 6 7 6 9 9 9 9 13 13 13 13 13 13 13 13 13 13 13 13 12 12 12 12 11 11 11 11
min_time_thread_start_total=6
max_time_thread_start_total=13
sum_duration_all_ops_total=

threads_final_keysum=98296626006928
threads_final_size=9830217
final_keysum=98296626006928
final_size=9830217
validate_result=success
Validation OK.
Structural validation OK.

total_find=283394316
total_rq=0
total_inserts=1431741
total_deletes=1432108
total_updates=2863849
total_queries=283394316
total_ops=286258165
find_throughput=28339431
rq_throughput=0
update_throughput=286384
query_throughput=28339431
total_throughput=28625816


total find                    : 283394316
total rq                      : 0
total inserts                 : 1431741
total deletes                 : 1432108
total updates                 : 2863849
total queries                 : 283394316
total ops                     : 286258165
find throughput               : 28339431
rq throughput                 : 0
update throughput             : 286384
query throughput              : 28339431
total throughput              : 28625816

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=238739379256835
total_execution_walltime=13.616s
REALTIME_MAIN_END_PERF_FORMAT=207745.565050875
[time_cmd_output] time_elapsed_sec=13.76, faults_major=0, faults_minor=233867, mem_maxresident_kb=936392, user_cputime=867.78, sys_cputime=5.31, percent_cpu=6343%
timeout=false
algorithm=sigouin_int_bst_kcas_validate.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2