__step=000410
__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 5.0 5.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000410.txt
__path_data=data/data000410.txt
__hostname=nasus
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x55f8ca8463c0
addrof(kcasDescriptors[1])=0x55f8ca846cc0
addrof(rdcssDescriptors[0])=0x55f8ca9663c0
addrof(rdcssDescriptors[1])=0x55f8ca966480
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=211791.899440132
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=5
DEL_FRAC=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=5.0 5.0
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.02s
prefilling_size=7800931 prefilling_elapsed_millis=1s
prefilling_size=8062371 prefilling_elapsed_millis=1.1s
prefilling_size=8289930 prefilling_elapsed_millis=1.201s
prefilling_size=8487604 prefilling_elapsed_millis=1.301s
prefilling_size=8662313 prefilling_elapsed_millis=1.401s
prefilling_size=8813735 prefilling_elapsed_millis=1.501s
prefilling_size=8947153 prefilling_elapsed_millis=1.601s
prefilling_size=9065346 prefilling_elapsed_millis=1.701s
prefilling_size=9167950 prefilling_elapsed_millis=1.801s
prefilling_size=9258539 prefilling_elapsed_millis=1.901s
prefilling_size=9338216 prefilling_elapsed_millis=2.002s
prefilling_size=9409746 prefilling_elapsed_millis=2.102s
prefilling_size=9472544 prefilling_elapsed_millis=2.202s
prefilling_size=9530081 prefilling_elapsed_millis=2.302s
prefilling_size=9580381 prefilling_elapsed_millis=2.402s
prefilling_size=9624023 prefilling_elapsed_millis=2.502s
prefilling_size=9662690 prefilling_elapsed_millis=2.602s
prefilling_size=9699265 prefilling_elapsed_millis=2.702s
prefilling_size=9732729 prefilling_elapsed_millis=2.802s
prefilling_size=9763157 prefilling_elapsed_millis=2.903s
prefilling_size=9788664 prefilling_elapsed_millis=3.003s
prefilling_size=9810701 prefilling_elapsed_millis=3.103s
finished prefilling to size 9810716 for expected size 10000000 keysum=98100600318313, performing 83135874 updates; total_prefilling_elapsed_ms=3204 ms)
pref_size=9810716
pref_millis=3204
prefill_elapsed_ms=3218
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=211795.157075287
REALTIME_END_PERF_FORMAT=211805.157174509

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

tree_stats_computeWalltime=0.164s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 249 485 916 1711 3193 5827 10261 17644 29053 45723 69110 99729 138038 184357 237516 294833 352512 404803 449256 481381 496280 493560 472857 436936 389537 334350 277027 220886 170850 127673 91790 63930 42528 27442 17234 10420 6006 3335 1711 881 434 187 83 37 7 3 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 3 5 32 65 141 307 772 1595 3437 6785 12084 20692 33302 49485 70555 96501 126278 159586 191918 221566 246998 263315 271181 267378 254573 233347 204871 174054 141385 111305 85120 61873 44376 29690 19507 12370 7599 4455 2573 1293 663 361 143 72 38 7 3
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 252 490 948 1776 3334 6134 11033 19239 32490 52508 81194 120421 171340 233842 308071 391334 478790 564389 641174 702947 743278 756875 744038 704314 644110 567697 481898 394940 312235 238978 176910 125803 86904 57132 36741 22790 13605 7790 4284 2174 1097 548 226 109 45 10 3
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 252 490 948 1776 3334 6134 11033 19239 32490 52508 81194 120421 171340 233842 308071 391334 478790 564389 641174 702947 743278 756875 744038 704314 644110 567697 481898 394940 312235 238978 176910 125803 86904 57132 36741 22790 13605 7790 4284 2174 1097 548 226 109 45 10 3
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.97656 1.95635 1.9449 1.90717 1.91385 1.88212 1.84871 1.81374 1.77166 1.72191 1.67554 1.63196 1.59467 1.55915 1.52905 1.49929 1.47008 1.44253 1.41881 1.39567 1.37257 1.3506 1.33094 1.31108 1.29415 1.2766 1.25991 1.24468 1.2313 1.21819 1.20603 1.19226 1.18262 1.16805 1.16276 1.15122 1.13975 1.13113 1.12182 1.10808 1.09936 1.10392 1.07117 1.11504 1.07339 1.06667 1 1

tree_stats_height=55
tree_stats_numInternals=6512835
tree_stats_numLeaves=3433660
tree_stats_numNodes=9946495
tree_stats_numKeys=9946495

tree_stats_avgDegreeInternal=1.52721
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34521
tree_stats_avgKeyDepth=29.0553


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:3 2:80 4:6961 8:249518 16:210419 32:391 64:83
    [2^00, 2^01]: 3
    (2^01, 2^02]: 80
    (2^02, 2^03]: 6961
    (2^03, 2^04]: 249518
    (2^04, 2^05]: 210419
    (2^05, 2^06]: 391
    (2^06, 2^07]: 83
sum_limbo_reclamation_event_size_total=7112206
count_limbo_reclamation_event_size_by_thread=7304 7304 7304 7303 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304 7304
count_limbo_reclamation_event_size_total=467455
sum_limbo_reclamation_event_count_total=467584
first_thread_announced_epoch_by_thread=18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894 18894
sum_num_inserts_by_thread=224936 226193 225563 222731 224615 226356 224777 225056 226856 226575 226033 223789 224438 225550 224326 222909 224336 224939 222866 224455 225044 225436 226236 225289 222872 226125 222674 226099 223495 222230 222539 223170 223666 226317 223352 224832 227410 226688 225223 224950 225651 226391 224085 224110 224041 224124 224716 224853 224113 222616 223423 222906 224936 225555 224547 223217 223846 224352 222110 222405 222255 223266 224129 223121
sum_num_inserts_total=14365714
sum_num_deletes_by_thread=224753 226199 225902 223008 223425 225183 225036 223306 226479 225772 226412 224189 224591 225798 224506 221361 224215 225189 222557 224616 225201 225136 226382 226074 224857 226006 223221 225599 223050 223304 222283 221980 223542 227072 224063 225256 227105 228005 224511 224578 225131 226707 224167 226390 223181 224555 224469 225373 224534 222998 223838 223476 223900 225398 224946 223455 224416 224681 222294 222208 222882 223285 224023 223470
sum_num_deletes_total=14369499
sum_num_searches_by_thread=4048393 4079227 4067543 4011807 4038235 4062435 4051940 4043119 4070636 4068026 4056614 4024298 4036965 4067240 4041945 4002866 4025147 4054032 4012963 4046488 4054916 4050300 4071313 4067441 4033646 4063120 4024266 4055432 4018768 4013155 3997888 4000739 4037235 4062676 4027295 4054126 4094110 4089839 4051552 4052318 4051192 4078725 4038550 4053148 4029297 4025436 4048374 4045346 4035677 4002895 4022143 4020144 4030142 4058182 4047536 4017328 4029269 4038904 3992682 3996218 4011637 4020234 4023869 4031661
sum_num_searches_total=258578643
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4498082 4531619 4519008 4457546 4486275 4513974 4501753 4491481 4523971 4520373 4509059 4472276 4485994 4518588 4490777 4447136 4473698 4504160 4458386 4495559 4505161 4500872 4523931 4518804 4481375 4515251 4470161 4507130 4465313 4458689 4442710 4445889 4484443 4516065 4474710 4504214 4548625 4544532 4501286 4501846 4501974 4531823 4486802 4503648 4476519 4474115 4497559 4495572 4484324 4448509 4469404 4466526 4478978 4509135 4497029 4464000 4477531 4487937 4437086 4440831 4456774 4466785 4472021 4478252
average_num_operations_total=4489279
stdev_num_operations_total=3278
sum_num_operations_total=287313856
min_num_operations_total=4437086
max_num_operations_total=4548625
first_time_thread_terminate_by_thread=10000099 10000099 10000099 10000099 10000103 10000103 10000103 10000103 10000102 10000103 10000102 10000102 10000100 10000100 10000101 10000101 10000102 10000102 10000102 10000102 10000100 10000100 10000100 10000100 10000102 10000102 10000102 10000102 10000101 10000101 10000101 10000101 10000103 10000103 10000103 10000103 10000103 10000103 10000103 10000103 10000099 10000099 10000099 10000099 10000102 10000102 10000102 10000102 10000101 10000101 10000101 10000101 10000100 10000100 10000100 10000100 10000101 10000101 10000101 10000101 10000100 10000100 10000100 10000100
min_time_thread_terminate_total=10000099
max_time_thread_terminate_total=10000103
first_time_thread_start_by_thread=6 7 6 7 8 8 8 8 8 8 8 8 9 9 9 9 10 10 10 10 12 12 12 12 12 12 12 12 9 9 9 9 10 10 10 10 13 13 13 13 13 13 13 13 11 10 10 11 11 11 11 11 11 11 11 11 10 10 10 10 13 13 13 13
min_time_thread_start_total=6
max_time_thread_start_total=13
sum_duration_all_ops_total=

threads_final_keysum=99457567321993
threads_final_size=9946495
final_keysum=99457567321993
final_size=9946495
validate_result=success
Validation OK.
Structural validation OK.

total_find=258578643
total_rq=0
total_inserts=14365714
total_deletes=14369499
total_updates=28735213
total_queries=258578643
total_ops=287313856
find_throughput=25857864
rq_throughput=0
update_throughput=2873521
query_throughput=25857864
total_throughput=28731385


total find                    : 258578643
total rq                      : 0
total inserts                 : 14365714
total deletes                 : 14369499
total updates                 : 28735213
total queries                 : 258578643
total ops                     : 287313856
find throughput               : 25857864
rq throughput                 : 0
update throughput             : 2873521
query throughput              : 25857864
total throughput              : 28731385

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=700191199738298
total_execution_walltime=13.482s
REALTIME_MAIN_END_PERF_FORMAT=211805.382614413
[time_cmd_output] time_elapsed_sec=13.63, faults_major=0, faults_minor=235642, mem_maxresident_kb=943460, user_cputime=869.41, sys_cputime=5.20, percent_cpu=6415%
timeout=false
algorithm=sigouin_int_bst_kcas_validate.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2