__step=000106
__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_avl_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=data000106.txt
__path_data=data/data000106.txt
__hostname=nasus
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x56369445a3c0
addrof(kcasDescriptors[1])=0x56369445acc0
addrof(rdcssDescriptors[0])=0x56369457a3c0
addrof(rdcssDescriptors[1])=0x56369457a480
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=208148.210560383
binary=./sigouin_int_avl_kcas_validate.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=sigouin_int_avl_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.02s
prefilling_size=7228551 prefilling_elapsed_millis=1s
prefilling_size=7546309 prefilling_elapsed_millis=1.101s
prefilling_size=7817762 prefilling_elapsed_millis=1.201s
prefilling_size=8059877 prefilling_elapsed_millis=1.301s
prefilling_size=8275612 prefilling_elapsed_millis=1.401s
prefilling_size=8462061 prefilling_elapsed_millis=1.501s
prefilling_size=8625423 prefilling_elapsed_millis=1.601s
prefilling_size=8778185 prefilling_elapsed_millis=1.701s
prefilling_size=8914620 prefilling_elapsed_millis=1.801s
prefilling_size=9033893 prefilling_elapsed_millis=1.901s
prefilling_size=9137671 prefilling_elapsed_millis=2.002s
prefilling_size=9231362 prefilling_elapsed_millis=2.102s
prefilling_size=9317001 prefilling_elapsed_millis=2.202s
prefilling_size=9391676 prefilling_elapsed_millis=2.302s
prefilling_size=9458240 prefilling_elapsed_millis=2.402s
prefilling_size=9515415 prefilling_elapsed_millis=2.502s
prefilling_size=9567165 prefilling_elapsed_millis=2.602s
prefilling_size=9611372 prefilling_elapsed_millis=2.702s
prefilling_size=9652789 prefilling_elapsed_millis=2.802s
prefilling_size=9690335 prefilling_elapsed_millis=2.903s
prefilling_size=9722211 prefilling_elapsed_millis=3.003s
prefilling_size=9751479 prefilling_elapsed_millis=3.103s
prefilling_size=9775825 prefilling_elapsed_millis=3.203s
prefilling_size=9797381 prefilling_elapsed_millis=3.304s
prefilling_size=9818822 prefilling_elapsed_millis=3.404s
finished prefilling to size 9818826 for expected size 10000000 keysum=98186192512109, performing 81508734 updates; total_prefilling_elapsed_ms=3504 ms)
pref_size=9818826
pref_millis=3504
prefill_elapsed_ms=3523
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=208151.765209547
REALTIME_END_PERF_FORMAT=208161.765310796

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

tree_stats_computeWalltime=0.063s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262056 519571 965869 1437958 1372965 682268 141529 8140 25 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 85 4122 63192 404684 1166659 1513994 849257 181210 10198 26
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262141 523693 1029061 1842642 2539624 2196262 990786 189350 10223 26
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262141 523693 1029061 1842642 2539624 2196262 990786 189350 10223 26
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99998 1.99808 1.97288 1.85201 1.59787 1.32418 1.14047 1.04827 1.011 1.0001 1

tree_stats_height=28
tree_stats_numInternals=5652524
tree_stats_numLeaves=4193427
tree_stats_numNodes=9845951
tree_stats_numKeys=9845951

tree_stats_avgDegreeInternal=1.74187
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.4259
tree_stats_avgKeyDepth=21.6642


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:170928 2:194781 4:34341 8:136
    [2^00, 2^01]: 170928
    (2^01, 2^02]: 194781
    (2^02, 2^03]: 34341
    (2^03, 2^04]: 136
sum_limbo_reclamation_event_size_total=776741
count_limbo_reclamation_event_size_by_thread=6295 6250 6258 6278 6330 6296 6301 6155 6241 6247 6227 6253 6295 6242 6299 6277 6197 6224 6205 6257 6193 6215 6308 6194 6216 6241 6308 6263 6273 6323 6210 6310 6199 6285 6204 6212 6262 6258 6231 6345 6344 6209 6293 6267 6343 6222 6264 6297 6202 6239 6144 6211 6221 6158 6324 6267 6240 6294 6312 6203 6265 6189 6214 6287
count_limbo_reclamation_event_size_total=400186
sum_limbo_reclamation_event_count_total=511933
first_thread_announced_epoch_by_thread=20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204 20204
sum_num_inserts_by_thread=24861 24842 25150 25156 25051 24775 24413 24803 24525 24593 24382 24994 24843 24830 24980 24867 25093 24812 24643 24433 24412 24507 24452 24541 24470 24853 24702 24943 24848 24702 24827 24639 25108 24697 24481 24626 24441 24589 23963 24860 24957 24750 24760 24857 24674 25035 24879 24709 24603 24606 24509 24296 24784 24350 24960 24882 24831 24701 24714 24557 24628 24798 24727 24749
sum_num_inserts_total=1582023
sum_num_deletes_by_thread=25241 24990 24974 24859 24819 24672 24499 24511 24602 24649 24226 24974 24897 24840 24863 24606 24642 24764 24650 24644 24545 24728 24683 24301 24264 24587 24853 24702 24967 24749 24738 24736 24855 24414 24513 24507 24627 24387 24502 25026 24946 24966 24812 24880 24956 24659 24888 24954 24285 24900 24324 24386 24115 24183 24643 25007 24684 24722 24792 24530 24742 24778 24399 24631
sum_num_deletes_total=1579788
sum_num_searches_by_thread=4969048 4960807 4945976 4904027 4908723 4912830 4878409 4889669 4867554 4855616 4832834 4897005 4920167 4913849 4899994 4879153 4925841 4928922 4899927 4870253 4874501 4871448 4834361 4832680 4844631 4911834 4889880 4884368 4913912 4891928 4898942 4875844 4927338 4880780 4860208 4880217 4857721 4861050 4839502 4931905 4927586 4934386 4904888 4904588 4916834 4931865 4899276 4899719 4872566 4858004 4826146 4815701 4832103 4841959 4908319 4909679 4919158 4896938 4882014 4880571 4911745 4914969 4880810 4884371
sum_num_searches_total=312977849
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5019150 5010639 4996100 4954042 4958593 4962277 4927321 4938983 4916681 4904858 4881442 4946973 4969907 4963519 4949837 4928626 4975576 4978498 4949220 4919330 4923458 4920683 4883496 4881522 4893365 4961274 4939435 4934013 4963727 4941379 4948507 4925219 4977301 4929891 4909202 4929350 4906789 4910026 4887967 4981791 4977489 4984102 4954460 4954325 4966464 4981559 4949043 4949382 4921454 4907510 4874979 4864383 4881002 4890492 4957922 4959568 4968673 4946361 4931520 4929658 4961115 4964545 4929936 4933751
average_num_operations_total=4939682
stdev_num_operations_total=4263
sum_num_operations_total=316139660
min_num_operations_total=4864383
max_num_operations_total=5019150
first_time_thread_terminate_by_thread=10000103 10000103 10000103 10000103 10000104 10000104 10000104 10000104 10000104 10000103 10000103 10000103 10000103 10000103 10000103 10000103 10000106 10000106 10000106 10000106 10000101 10000103 10000103 10000101 10000105 10000105 10000105 10000105 10000101 10000101 10000103 10000101 10000104 10000101 10000104 10000101 10000102 10000102 10000103 10000103 10000103 10000104 10000105 10000104 10000102 10000102 10000104 10000102 10000105 10000105 10000105 10000105 10000104 10000104 10000104 10000104 10000104 10000104 10000104 10000104 10000105 10000105 10000105 10000105
min_time_thread_terminate_total=10000101
max_time_thread_terminate_total=10000106
first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 5 5 5 5 6 6 6 6 5 5 5 5 7 6 7 6 6 6 6 6 6 6 6 6 7 7 7 7 4 4 4 4 8 8 9 8 7 7 7 7 8 8 8 8 5 5 5 5 9 9 9 9 4 4 4 4
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=98457901478431
threads_final_size=9845951
final_keysum=98457901478431
final_size=9845951
validate_result=success
Validation OK.
Structural validation OK.

total_find=312977849
total_rq=0
total_inserts=1582023
total_deletes=1579788
total_updates=3161811
total_queries=312977849
total_ops=316139660
find_throughput=31297784
rq_throughput=0
update_throughput=316181
query_throughput=31297784
total_throughput=31613966


total find                    : 312977849
total rq                      : 0
total inserts                 : 1582023
total deletes                 : 1579788
total updates                 : 3161811
total queries                 : 312977849
total ops                     : 316139660
find throughput               : 31297784
rq throughput                 : 0
update throughput             : 316181
query throughput              : 31297784
total throughput              : 31613966

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=312375124339255
total_execution_walltime=13.693s
REALTIME_MAIN_END_PERF_FORMAT=208161.904903320
[time_cmd_output] time_elapsed_sec=13.84, faults_major=0, faults_minor=233792, mem_maxresident_kb=936112, user_cputime=881.49, sys_cputime=5.61, percent_cpu=6407%
timeout=false
algorithm=sigouin_int_avl_kcas_validate.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2