__step=000442
__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 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000442.txt
__path_data=data/data000442.txt
__hostname=jax
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x564d0bf11400
addrof(kcasDescriptors[1])=0x564d0bf11d00
addrof(rdcssDescriptors[0])=0x564d0c031400
addrof(rdcssDescriptors[1])=0x564d0c0314c0
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=6984233.512977444
binary=./sigouin_int_avl_kcas_validate.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
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=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
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,96,97,98,99,100,101,102,103,104,105,106,107,108,109,110,111,112,113,114,115,116,117,118,119


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.004s
prefilling_size=6189374 prefilling_elapsed_millis=1.001s
prefilling_size=6486693 prefilling_elapsed_millis=1.101s
prefilling_size=6754742 prefilling_elapsed_millis=1.201s
prefilling_size=6999937 prefilling_elapsed_millis=1.301s
prefilling_size=7224926 prefilling_elapsed_millis=1.401s
prefilling_size=7427987 prefilling_elapsed_millis=1.502s
prefilling_size=7614065 prefilling_elapsed_millis=1.602s
prefilling_size=7787221 prefilling_elapsed_millis=1.702s
prefilling_size=7944350 prefilling_elapsed_millis=1.802s
prefilling_size=8086641 prefilling_elapsed_millis=1.903s
prefilling_size=8220029 prefilling_elapsed_millis=2.003s
prefilling_size=8343259 prefilling_elapsed_millis=2.103s
prefilling_size=8456042 prefilling_elapsed_millis=2.203s
prefilling_size=8560869 prefilling_elapsed_millis=2.303s
prefilling_size=8659625 prefilling_elapsed_millis=2.404s
prefilling_size=8749247 prefilling_elapsed_millis=2.504s
prefilling_size=8831170 prefilling_elapsed_millis=2.604s
prefilling_size=8908109 prefilling_elapsed_millis=2.704s
prefilling_size=8978393 prefilling_elapsed_millis=2.804s
prefilling_size=9044961 prefilling_elapsed_millis=2.905s
prefilling_size=9107061 prefilling_elapsed_millis=3.005s
prefilling_size=9164004 prefilling_elapsed_millis=3.105s
prefilling_size=9218257 prefilling_elapsed_millis=3.205s
prefilling_size=9267711 prefilling_elapsed_millis=3.305s
prefilling_size=9313974 prefilling_elapsed_millis=3.406s
prefilling_size=9356579 prefilling_elapsed_millis=3.506s
prefilling_size=9395903 prefilling_elapsed_millis=3.606s
prefilling_size=9433487 prefilling_elapsed_millis=3.706s
prefilling_size=9468319 prefilling_elapsed_millis=3.806s
prefilling_size=9501524 prefilling_elapsed_millis=3.907s
prefilling_size=9530421 prefilling_elapsed_millis=4.007s
prefilling_size=9558845 prefilling_elapsed_millis=4.107s
prefilling_size=9586566 prefilling_elapsed_millis=4.207s
prefilling_size=9611520 prefilling_elapsed_millis=4.307s
prefilling_size=9633707 prefilling_elapsed_millis=4.408s
prefilling_size=9654577 prefilling_elapsed_millis=4.508s
prefilling_size=9674467 prefilling_elapsed_millis=4.608s
prefilling_size=9695966 prefilling_elapsed_millis=4.708s
prefilling_size=9713094 prefilling_elapsed_millis=4.808s
prefilling_size=9730511 prefilling_elapsed_millis=4.909s
prefilling_size=9745721 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 254292 keys with 48 threads...
tid=0 thread_prefill_amount=295786 percent_done_estimate=5583.2 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=299952 percent_done_estimate=5661.9 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0394382s
prefilling_size=10000000 prefilling_elapsed_millis=0.039s
finished prefilling to size 10000000 for expected size 10000000 keysum=100008820508801, performing 91712660 updates; total_prefilling_elapsed_ms=39 ms)
pref_size=10000000
pref_millis=39
prefill_elapsed_ms=39
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6984238.828160695
REALTIME_END_PERF_FORMAT=6984248.828242681

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

tree_stats_computeWalltime=0.069s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262116 521854 986448 1492818 1412593 674004 133171 8192 90 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 28 2181 50290 396937 1222776 1573995 839865 169859 10325 110
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524035 1036738 1889755 2635369 2247999 973036 178051 10415 110
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524035 1036738 1889755 2635369 2247999 973036 178051 10415 110
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99914 1.98254 1.8713 1.6046 1.317 1.13302 1.04612 1.01249 1.00192 1

tree_stats_height=28
tree_stats_numInternals=5753429
tree_stats_numLeaves=4266366
tree_stats_numNodes=10019795
tree_stats_numKeys=10019795

tree_stats_avgDegreeInternal=1.74153
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42579
tree_stats_avgKeyDepth=21.662


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:45 2:1427 4:51988 8:374003 16:52392
    [2^00, 2^01]: 45
    (2^01, 2^02]: 1427
    (2^02, 2^03]: 51988
    (2^03, 2^04]: 374003
    (2^04, 2^05]: 52392
sum_limbo_reclamation_event_size_total=5472673
count_limbo_reclamation_event_size_by_thread=9997 9997 9997 9997 9997 9996 9998 9997 9997 9997 9997 9997 9996 9997 9997 9997 9997 9997 9997 9997 9997 9997 9996 9997 9997 9997 9997 9997 9997 9997 9997 9997 9997 9998 9997 9997 9997 9998 9997 9997 9997 9997 9997 9997 9997 9996 9997 9997
count_limbo_reclamation_event_size_total=479855
sum_limbo_reclamation_event_count_total=542925
first_thread_announced_epoch_by_thread=31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150 31150
sum_num_inserts_by_thread=257135 257454 260126 259392 259240 256829 256995 258410 260946 260897 258712 258340 258453 259095 261059 260907 258182 256870 255101 257958 259535 259653 257298 255489 257365 258676 260698 260325 258898 256836 256867 258859 260159 261204 257956 256708 258458 259749 258425 260054 258684 257437 256705 257538 258711 259568 256674 255922
sum_num_inserts_total=12406552
sum_num_deletes_by_thread=256968 257625 259792 259878 258689 256745 255888 258138 261701 259971 258565 256802 258749 259659 261112 260299 259219 258470 255757 257607 259400 260360 257125 254956 257492 257783 260414 260278 258657 257169 258345 258824 261743 260903 257796 257466 257040 259237 259753 260552 258628 258289 255903 256266 258391 259642 257079 255742
sum_num_deletes_total=12406867
sum_num_searches_by_thread=4628807 4641259 4688015 4668606 4665737 4617884 4627753 4646312 4701425 4696865 4648885 4624140 4648285 4670234 4691949 4694161 4656233 4634391 4598985 4641986 4659776 4687902 4628836 4600625 4624503 4635162 4685571 4679951 4656161 4620289 4640337 4657612 4700109 4704680 4641566 4622893 4647221 4673272 4679845 4684306 4662913 4639158 4615771 4628979 4660307 4674822 4628067 4597998
sum_num_searches_total=223330544
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5142910 5156338 5207933 5187876 5183666 5131458 5140636 5162860 5224072 5217733 5166162 5139282 5165487 5188988 5214120 5215367 5173634 5149731 5109843 5157551 5178711 5207915 5143259 5111070 5139360 5151621 5206683 5200554 5173716 5134294 5155549 5175295 5222011 5226787 5157318 5137067 5162719 5192258 5198023 5204912 5180225 5154884 5128379 5142783 5177409 5194032 5141820 5109662
average_num_operations_total=5169665
stdev_num_operations_total=4680
sum_num_operations_total=248143963
min_num_operations_total=5109662
max_num_operations_total=5226787
first_time_thread_terminate_by_thread=10000140 10000081 10000081 10000081 10000081 10000084 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000082 10000083 10000081 10000081 10000081 10000081 10000082 10000081 10000081 10000081 10000082 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000082 10000082 10000082 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081
min_time_thread_terminate_total=10000081
max_time_thread_terminate_total=10000140
first_time_thread_start_by_thread=9 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=100180306940587
threads_final_size=10019795
final_keysum=100180306940587
final_size=10019795
validate_result=success
Validation OK.
Structural validation OK.

total_find=223330544
total_rq=0
total_inserts=12406552
total_deletes=12406867
total_updates=24813419
total_queries=223330544
total_ops=248143963
find_throughput=22333054
rq_throughput=0
update_throughput=2481341
query_throughput=22333054
total_throughput=24814396


total find                    : 223330544
total rq                      : 0
total inserts                 : 12406552
total deletes                 : 12406867
total updates                 : 24813419
total queries                 : 223330544
total ops                     : 248143963
find throughput               : 22333054
rq throughput                 : 0
update throughput             : 2481341
query throughput              : 22333054
total throughput              : 24814396

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=27.5945
PAPI_L3_TCM=9.46982
PAPI_TOT_CYC=5393.99
PAPI_TOT_INS=1378.68
garbage=1115347735254058
total_execution_walltime=15.552s
REALTIME_MAIN_END_PERF_FORMAT=6984249.65554715
[time_cmd_output] time_elapsed_sec=15.72, faults_major=0, faults_minor=235588, mem_maxresident_kb=943564, user_cputime=738.24, sys_cputime=3.74, percent_cpu=4719%
timeout=false
algorithm=sigouin_int_avl_kcas_validate.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2