__step=000074
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 180 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=data000074.txt
__path_data=data/data000074.txt
__hostname=nasus
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x55a856c213c0
addrof(kcasDescriptors[1])=0x55a856c21cc0
addrof(rdcssDescriptors[0])=0x55a856d413c0
addrof(rdcssDescriptors[1])=0x55a856d41480
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=220674.987850949
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.017s
prefilling_size=7724529 prefilling_elapsed_millis=1s
prefilling_size=7982619 prefilling_elapsed_millis=1.1s
prefilling_size=8210754 prefilling_elapsed_millis=1.201s
prefilling_size=8411190 prefilling_elapsed_millis=1.301s
prefilling_size=8587621 prefilling_elapsed_millis=1.401s
prefilling_size=8741874 prefilling_elapsed_millis=1.501s
prefilling_size=8879776 prefilling_elapsed_millis=1.601s
prefilling_size=9002153 prefilling_elapsed_millis=1.701s
prefilling_size=9108963 prefilling_elapsed_millis=1.801s
prefilling_size=9205100 prefilling_elapsed_millis=1.901s
prefilling_size=9287985 prefilling_elapsed_millis=2.002s
prefilling_size=9363210 prefilling_elapsed_millis=2.102s
prefilling_size=9430482 prefilling_elapsed_millis=2.202s
prefilling_size=9490164 prefilling_elapsed_millis=2.302s
prefilling_size=9544271 prefilling_elapsed_millis=2.402s
prefilling_size=9591672 prefilling_elapsed_millis=2.502s
prefilling_size=9633969 prefilling_elapsed_millis=2.602s
prefilling_size=9671004 prefilling_elapsed_millis=2.702s
prefilling_size=9704208 prefilling_elapsed_millis=2.802s
prefilling_size=9735464 prefilling_elapsed_millis=2.903s
prefilling_size=9762697 prefilling_elapsed_millis=3.003s
prefilling_size=9787924 prefilling_elapsed_millis=3.103s
prefilling_size=9809517 prefilling_elapsed_millis=3.203s
finished prefilling to size 9809525 for expected size 10000000 keysum=98076294815175, performing 84627604 updates; total_prefilling_elapsed_ms=3304 ms)
pref_size=9809525
pref_millis=3304
prefill_elapsed_ms=3318
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=220678.341131402
REALTIME_END_PERF_FORMAT=220688.341177553
###############################################################################
################################## 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 1868 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.253s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 252 495 957 1817 3383 6215 11049 18954 31364 49762 75897 110376 153919 204610 259282 314461 366580 409832 442557 462685 467330 458590 436379 402758 360916 314332 266134 219458 175889 137530 104517 76975 55513 39121 26362 17532 11214 6940 4137 2307 1219 658 335 155 62 21 5 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 1 4 22 51 136 290 779 1729 3575 7092 12929 22424 36150 55525 80492 109387 139594 172318 200254 223861 242245 250819 253540 247342 234026 213956 190160 164131 137582 111815 89167 69025 51181 37332 26979 18184 12245 7985 5023 3063 1753 892 510 261 128 58 20 5 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 253 499 979 1868 3519 6505 11828 20683 34939 56854 88826 132800 190069 260135 339774 423848 506174 582150 642811 686546 709575 709409 689919 650100 594942 528288 456294 383589 313471 249345 193684 146000 106694 76453 53341 35716 23459 14925 9160 5370 2972 1550 845 416 190 79 25 6 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 253 499 979 1868 3519 6505 11828 20683 34939 56854 88826 132800 190069 260135 339774 423848 506174 582150 642811 686546 709575 709409 689919 650100 594942 528288 456294 383589 313471 249345 193684 146000 106694 76453 53341 35716 23459 14925 9160 5370 2972 1550 845 416 190 79 25 6 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.98438 1.97628 1.96994 1.93054 1.91113 1.88718 1.86287 1.81451 1.77286 1.72956 1.68709 1.64061 1.6001 1.55883 1.51959 1.48434 1.45232 1.42588 1.4002 1.37957 1.35961 1.34116 1.32609 1.30978 1.29562 1.28132 1.26872 1.25741 1.24509 1.23433 1.22521 1.21418 1.20355 1.19626 1.186 1.17536 1.16595 1.15819 1.14874 1.13461 1.12384 1.11137 1.12065 1.09586 1.08413 1.08947 1.05063 1.04 1 1
tree_stats_height=57
tree_stats_numInternals=6511091
tree_stats_numLeaves=3436042
tree_stats_numNodes=9947133
tree_stats_numKeys=9947133
tree_stats_avgDegreeInternal=1.52772
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34543
tree_stats_avgKeyDepth=29.0385
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:2 2:65 4:7019 8:249529 16:210405 32:241 64:131
[2^00, 2^01]: 2
(2^01, 2^02]: 65
(2^02, 2^03]: 7019
(2^03, 2^04]: 249529
(2^04, 2^05]: 210405
(2^05, 2^06]: 241
(2^06, 2^07]: 131
sum_limbo_reclamation_event_size_total=7110683
count_limbo_reclamation_event_size_by_thread=7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303 7303
count_limbo_reclamation_event_size_total=467392
sum_limbo_reclamation_event_count_total=467520
first_thread_announced_epoch_by_thread=18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984 18984
sum_num_inserts_by_thread=225272 225393 223853 223714 224787 226777 223523 226263 223087 225145 222440 223251 222893 222545 225250 225627 223325 225184 223205 224223 226797 226415 225504 223006 224073 222712 223009 224194 224715 223813 224009 224962 224718 226652 225053 226554 224110 226808 225033 223325 224496 225346 223343 226678 225842 225099 226455 226091 226003 226045 222158 221484 224764 222278 224512 221748 224313 223750 223267 222562 223983 225312 222516 226399
sum_num_inserts_total=14365663
sum_num_deletes_by_thread=225519 224896 225106 224447 224281 226368 224551 225243 222849 224354 221408 223150 222591 222898 225797 225300 225058 225021 223847 223334 226341 225384 223899 222864 225714 223394 223161 224285 224946 223593 224038 226275 225771 225128 224663 225463 224815 226274 225599 223095 225607 226442 222002 227241 225232 225109 225820 225631 225683 225455 222292 221064 224515 223402 222997 221924 224373 224249 221753 223569 223522 224656 223697 225784
sum_num_deletes_total=14362739
sum_num_searches_by_thread=4043444 4045104 4039391 4017944 4040891 4081057 4025561 4057317 4013187 4050847 4000999 4035892 4001805 4005410 4063475 4056415 4035043 4040582 4028696 4034751 4086104 4072405 4048094 4013434 4051086 4011336 4001186 4037226 4035893 4030889 4020557 4060401 4056184 4058793 4043373 4071741 4037291 4071508 4053848 4022254 4060005 4059472 4011755 4083813 4045652 4049441 4077229 4081006 4068187 4064157 4002701 3988985 4041415 4003920 4034035 3991515 4038738 4030573 4016466 4012710 4034157 4036040 4020039 4076347
sum_num_searches_total=258529772
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4494235 4495393 4488350 4466105 4489959 4534202 4473635 4508823 4459123 4500346 4444847 4482293 4447289 4450853 4514522 4507342 4483426 4490787 4475748 4482308 4539242 4524204 4497497 4459304 4500873 4457442 4447356 4485705 4485554 4478295 4468604 4511638 4506673 4510573 4493089 4523758 4486216 4524590 4504480 4468674 4510108 4511260 4457100 4537732 4496726 4499649 4529504 4532728 4519873 4515657 4447151 4431533 4490694 4449600 4481544 4435187 4487424 4478572 4461486 4458841 4481662 4486008 4466252 4528530
average_num_operations_total=4488408
stdev_num_operations_total=3422
sum_num_operations_total=287258174
min_num_operations_total=4431533
max_num_operations_total=4539242
first_time_thread_terminate_by_thread=10000049 10000049 10000049 10000049 10000049 10000049 10000049 10000049 10000051 10000051 10000051 10000051 10000050 10000050 10000050 10000050 10000048 10000048 10000048 10000048 10000050 10000050 10000050 10000050 10000047 10000048 10000047 10000048 10000048 10000048 10000048 10000048 10000051 10000051 10000051 10000051 10000050 10000050 10000050 10000050 10000049 10000049 10000049 10000049 10000048 10000048 10000048 10000048 10000048 10000048 10000048 10000049 10000049 10000049 10000049 10000049 10000050 10000050 10000050 10000050 10000048 10000047 10000047 10000047
min_time_thread_terminate_total=10000047
max_time_thread_terminate_total=10000051
first_time_thread_start_by_thread=5 5 5 5 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 7 7 7 7 4 4 4 4 5 5 5 5 7 8 7 7 5 5 5 4 5 5 5 5 7 7 7 7 8 8 8 8 12 12 12 12 5 5 5 5 10 9 9 9
min_time_thread_start_total=4
max_time_thread_start_total=12
sum_duration_all_ops_total=
threads_final_keysum=99469460405301
threads_final_size=9947133
final_keysum=99469460405301
final_size=9947133
validate_result=success
Validation OK.
Structural validation OK.
total_find=258529772
total_rq=0
total_inserts=14365663
total_deletes=14362739
total_updates=28728402
total_queries=258529772
total_ops=287258174
find_throughput=25852977
rq_throughput=0
update_throughput=2872840
query_throughput=25852977
total_throughput=28725817
total find : 258529772
total rq : 0
total inserts : 14365663
total deletes : 14362739
total updates : 28728402
total queries : 258529772
total ops : 287258174
find throughput : 25852977
rq throughput : 0
update throughput : 2872840
query throughput : 25852977
total throughput : 28725817
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=399604356987015
total_execution_walltime=13.677s
REALTIME_MAIN_END_PERF_FORMAT=220688.668768593
[time_cmd_output] time_elapsed_sec=13.83, faults_major=0, faults_minor=235649, mem_maxresident_kb=943752, user_cputime=876.09, sys_cputime=4.87, percent_cpu=6370%
timeout=false
algorithm=sigouin_int_bst_kcas_validate.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2