__step=000441
__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 5.0 5.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000441.txt
__path_data=data/data000441.txt
__hostname=nasus
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x56203a1543c0
addrof(kcasDescriptors[1])=0x56203a154cc0
addrof(rdcssDescriptors[0])=0x56203a2743c0
addrof(rdcssDescriptors[1])=0x56203a274480
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=212193.400375100
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=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.013s
prefilling_size=7314488 prefilling_elapsed_millis=1s
prefilling_size=7623232 prefilling_elapsed_millis=1.1s
prefilling_size=7894445 prefilling_elapsed_millis=1.201s
prefilling_size=8133788 prefilling_elapsed_millis=1.301s
prefilling_size=8345290 prefilling_elapsed_millis=1.401s
prefilling_size=8529917 prefilling_elapsed_millis=1.501s
prefilling_size=8697162 prefilling_elapsed_millis=1.601s
prefilling_size=8842005 prefilling_elapsed_millis=1.701s
prefilling_size=8969037 prefilling_elapsed_millis=1.801s
prefilling_size=9083419 prefilling_elapsed_millis=1.901s
prefilling_size=9186068 prefilling_elapsed_millis=2.002s
prefilling_size=9274978 prefilling_elapsed_millis=2.102s
prefilling_size=9355754 prefilling_elapsed_millis=2.202s
prefilling_size=9426381 prefilling_elapsed_millis=2.302s
prefilling_size=9489815 prefilling_elapsed_millis=2.402s
prefilling_size=9546193 prefilling_elapsed_millis=2.502s
prefilling_size=9597776 prefilling_elapsed_millis=2.602s
prefilling_size=9642787 prefilling_elapsed_millis=2.702s
prefilling_size=9681752 prefilling_elapsed_millis=2.802s
prefilling_size=9717348 prefilling_elapsed_millis=2.903s
prefilling_size=9746807 prefilling_elapsed_millis=3.003s
prefilling_size=9773303 prefilling_elapsed_millis=3.103s
prefilling_size=9795929 prefilling_elapsed_millis=3.203s
prefilling_size=9818336 prefilling_elapsed_millis=3.303s
finished prefilling to size 9818329 for expected size 10000000 keysum=98193383776239, performing 80505668 updates; total_prefilling_elapsed_ms=3404 ms)
pref_size=9818329
pref_millis=3404
prefill_elapsed_ms=3424
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=212196.850984668
REALTIME_END_PERF_FORMAT=212206.851081512

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10.1s
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.065s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262025 517964 947996 1392225 1370065 752372 194748 19095 520 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 113 5542 75131 409218 1096006 1465570 917863 247696 24232 638
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262138 523506 1023127 1801443 2466071 2217942 1112611 266791 24752 638
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262138 523506 1023127 1801443 2466071 2217942 1112611 266791 24752 638
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99995 1.99749 1.96496 1.83416 1.5961 1.34382 1.16242 1.06475 1.0212 1.00477 1

tree_stats_height=28
tree_stats_numInternals=5719153
tree_stats_numLeaves=4242009
tree_stats_numNodes=9961162
tree_stats_numKeys=9961162

tree_stats_avgDegreeInternal=1.74172
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42585
tree_stats_avgKeyDepth=21.7296


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:3 2:76 4:7073 8:262744 16:225050 32:301 64:73 128:39
    [2^00, 2^01]: 3
    (2^01, 2^02]: 76
    (2^02, 2^03]: 7073
    (2^03, 2^04]: 262744
    (2^04, 2^05]: 225050
    (2^05, 2^06]: 301
    (2^06, 2^07]: 73
    (2^07, 2^08]: 39
sum_limbo_reclamation_event_size_total=7556278
count_limbo_reclamation_event_size_by_thread=7740 7740 7740 7739 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740 7740
count_limbo_reclamation_event_size_total=495359
sum_limbo_reclamation_event_count_total=495488
first_thread_announced_epoch_by_thread=19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628 19628
sum_num_inserts_by_thread=241063 241260 240134 238474 239340 239928 239166 237680 238147 237632 235881 239647 239300 238423 238146 239237 239852 239192 239440 237217 237177 237243 236805 237203 236082 240026 239442 239006 239583 239265 238917 237910 238684 237940 237343 236627 237469 238313 235906 241006 239517 240043 240021 240226 240250 239829 238813 237883 236495 236691 235563 235370 236337 240288 239488 238594 238935 238359 237565 239442 238820 237229 238257 238559
sum_num_inserts_total=15263680
sum_num_deletes_by_thread=241942 241711 241303 238463 239961 238950 238476 238186 238057 237340 235949 239701 238581 238361 237950 239411 239817 239251 239458 237019 237172 237979 236246 236785 235659 239794 239196 238028 239029 238952 238874 238152 239546 238730 237303 237959 236609 237676 236261 241541 239582 239968 239293 239581 240103 239704 238770 237098 235983 236831 235301 234566 236537 240161 239265 239216 239217 238792 237909 238334 238576 237616 238175 238203
sum_num_deletes_total=15260159
sum_num_searches_by_thread=4353132 4351600 4335550 4298858 4306688 4304047 4295102 4286170 4277149 4269648 4255753 4307280 4302167 4302090 4291464 4288812 4320003 4309653 4291422 4272887 4268029 4273757 4261314 4261364 4251297 4326969 4310011 4302177 4310025 4306503 4297102 4290991 4315479 4296408 4278993 4271981 4275408 4278928 4259949 4336849 4328057 4322851 4308295 4306692 4318975 4309842 4293873 4275385 4256935 4259068 4240709 4240938 4248853 4319648 4296338 4299944 4307377 4296163 4295336 4291448 4298678 4283712 4279469 4291234
sum_num_searches_total=274762829
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4836137 4834571 4816987 4775795 4785989 4782925 4772744 4762036 4753353 4744620 4727583 4786628 4780048 4778874 4767560 4767460 4799672 4788096 4770320 4747123 4742378 4748979 4734365 4735352 4723038 4806789 4788649 4779211 4788637 4784720 4774893 4767053 4793709 4773078 4753639 4746567 4749486 4754917 4732116 4819396 4807156 4802862 4787609 4786499 4799328 4789375 4771456 4750366 4729413 4732590 4711573 4710874 4721727 4800097 4775091 4777754 4785529 4773314 4770810 4769224 4776074 4758557 4755901 4767996
average_num_operations_total=4770104
stdev_num_operations_total=3511
sum_num_operations_total=305286668
min_num_operations_total=4710874
max_num_operations_total=4836137
first_time_thread_terminate_by_thread=10000101 10000101 10000101 10000953 10000102 10000102 10000102 10000102 10000100 10000100 10000100 10000100 10000100 10000100 10000101 10000100 10000098 10000098 10000098 10000098 10000102 10000102 10000102 10000102 10000101 10000101 10000100 10000101 10000099 10000099 10000099 10000099 10000098 10000098 10000098 10000098 10000100 10000100 10000100 10000100 10000099 10000099 10000099 10000099 10000102 10000102 10000102 10000102 10000098 10000098 10000098 10000098 10000099 10000099 10000099 10000099 10000102 10000102 10000102 10000102 10000097 10000097 10000097 10000097
min_time_thread_terminate_total=10000097
max_time_thread_terminate_total=10000953
first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 4 4 5 4 5 4 5 5 8 8 8 8 8 8 8 8 7 7 7 7 9 9 9 9 9 9 9 9 7 7 7 7 7 7 7 7 8 8 8 8 6 6 6 6 6 6 5 5 4 4 4 4 6 7 7 6
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99612686330533
threads_final_size=9961162
final_keysum=99612686330533
final_size=9961162
validate_result=success
Validation OK.
Structural validation OK.

total_find=274762829
total_rq=0
total_inserts=15263680
total_deletes=15260159
total_updates=30523839
total_queries=274762829
total_ops=305286668
find_throughput=27476282
rq_throughput=0
update_throughput=3052383
query_throughput=27476282
total_throughput=30528666


total find                    : 274762829
total rq                      : 0
total inserts                 : 15263680
total deletes                 : 15260159
total updates                 : 30523839
total queries                 : 274762829
total ops                     : 305286668
find throughput               : 27476282
rq throughput                 : 0
update throughput             : 3052383
query throughput              : 27476282
total throughput              : 30528666

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=383185739661009
total_execution_walltime=13.689s
REALTIME_MAIN_END_PERF_FORMAT=212207.89917765
[time_cmd_output] time_elapsed_sec=13.83, faults_major=0, faults_minor=235457, mem_maxresident_kb=942472, user_cputime=872.52, sys_cputime=5.69, percent_cpu=6346%
timeout=false
algorithm=sigouin_int_avl_kcas_validate.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1