__step=000444
__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 96 -nprefill 96 -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=data000444.txt
__path_data=data/data000444.txt
__hostname=jax
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x5598b8f49400
addrof(kcasDescriptors[1])=0x5598b8f49d00
addrof(rdcssDescriptors[0])=0x5598b9069400
addrof(rdcssDescriptors[1])=0x5598b90694c0
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=6984262.510457858
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=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.009s
prefilling_size=8333943 prefilling_elapsed_millis=1.001s
prefilling_size=8584935 prefilling_elapsed_millis=1.101s
prefilling_size=8797351 prefilling_elapsed_millis=1.201s
prefilling_size=8977218 prefilling_elapsed_millis=1.301s
prefilling_size=9128759 prefilling_elapsed_millis=1.402s
prefilling_size=9257768 prefilling_elapsed_millis=1.502s
prefilling_size=9366742 prefilling_elapsed_millis=1.602s
prefilling_size=9460354 prefilling_elapsed_millis=1.702s
prefilling_size=9540511 prefilling_elapsed_millis=1.802s
prefilling_size=9606273 prefilling_elapsed_millis=1.903s
prefilling_size=9665089 prefilling_elapsed_millis=2.003s
prefilling_size=9714122 prefilling_elapsed_millis=2.103s
prefilling_size=9756174 prefilling_elapsed_millis=2.203s
prefilling_size=9792222 prefilling_elapsed_millis=2.304s
prefilling_size=9822653 prefilling_elapsed_millis=2.404s
finished prefilling to size 9822664 for expected size 10000000 keysum=98245837455160, performing 80596982 updates; total_prefilling_elapsed_ms=2505 ms)
pref_size=9822664
pref_millis=2505
prefill_elapsed_ms=2528
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6984265.237166007
REALTIME_END_PERF_FORMAT=6984275.237310386

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

tree_stats_computeWalltime=0.044s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262117 521604 983362 1483790 1408237 675469 130384 6665 25 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 27 2425 52397 398565 1212344 1567840 844436 166417 8309 28
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524029 1035759 1882355 2620581 2243309 974820 173082 8334 28
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262144 524029 1035759 1882355 2620581 2243309 974820 173082 8334 28
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99911 1.98116 1.86796 1.60392 1.31866 1.13344 1.0438 1.00964 1.00036 1

tree_stats_height=28
tree_stats_numInternals=5733796
tree_stats_numLeaves=4252788
tree_stats_numNodes=9986584
tree_stats_numKeys=9986584

tree_stats_avgDegreeInternal=1.74171
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42585
tree_stats_avgKeyDepth=21.6594


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:1 2:1 4:32 8:27324 16:528170 32:21240
    [2^00, 2^01]: 1
    (2^01, 2^02]: 1
    (2^02, 2^03]: 32
    (2^03, 2^04]: 27324
    (2^04, 2^05]: 528170
    (2^05, 2^06]: 21240
sum_limbo_reclamation_event_size_total=13236023
count_limbo_reclamation_event_size_by_thread=6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008 6008
count_limbo_reclamation_event_size_total=576768
sum_limbo_reclamation_event_count_total=576960
first_thread_announced_epoch_by_thread=13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908 13908
sum_num_inserts_by_thread=275911 278078 279933 279108 277824 275085 276063 278204 281067 279846 276743 275748 276867 279814 280333 280358 278985 277319 275005 276483 277887 278772 277533 272683 275730 276782 278046 276870 277529 274077 277051 275828 278805 279568 276813 276215 276379 278542 279807 279793 277805 275059 275101 275646 278070 278671 276693 273508 275808 277225 278971 280459 275630 275205 275891 277605 280691 280849 278366 275556 277162 278306 280207 280309 276887 274318 274477 275428 278283 279315 276726 274332 276688 277962 280217 278818 277726 276573 276923 278844 281158 281441 278606 276933 276606 277680 279559 279429 277585 274600 274666 275763 279488 279501 276333 275107
sum_num_inserts_total=26640249
sum_num_deletes_by_thread=275958 277268 280293 278161 278417 274560 277116 276502 281303 280373 276208 276202 277890 279319 280300 280186 279151 275902 274012 276892 277851 279749 275763 272608 275689 276738 279751 277423 276180 274691 276670 276477 279923 280084 276840 275564 277413 277590 279441 278711 277849 275547 274856 276446 278091 279401 276565 274747 276440 277287 280313 278825 277431 276073 276803 277286 281202 279755 277444 275169 276888 279313 278722 279722 277205 275148 274313 275750 279309 278763 277136 274074 276368 277840 280225 278563 277148 276650 277274 279161 281055 281545 278820 276937 277503 277895 279994 279869 277481 275805 274656 276248 279416 279542 277272 275798
sum_num_deletes_total=26648107
sum_num_searches_by_thread=4971132 4997903 5028648 5018696 4997213 4945680 4979150 5000637 5050822 5051987 4981491 4965292 4990812 5021724 5043329 5040585 5018029 4983719 4960910 4970749 5013030 5031117 4968952 4912541 4957391 4986930 5022512 5005464 4997051 4938302 4973805 4994114 5043063 5039599 4985636 4956795 4976739 5003956 5028550 5033328 4999073 4962879 4949055 4970557 5009460 5025875 4975617 4938321 4969545 4999845 5044760 5037113 4982494 4967580 4975969 4993485 5047894 5048238 5007416 4967554 4984537 5013838 5033725 5036402 4996284 4947668 4930520 4960410 5022790 5012695 4987404 4948798 4969780 4994261 5038218 5025255 4984348 4967932 4983569 5007045 5058809 5057375 5013339 4983575 4982674 5011095 5035727 5034631 4993155 4945499 4941171 4965461 5033929 5036344 4998587 4961487
sum_num_searches_total=479658450
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=5523001 5553249 5588874 5575965 5553454 5495325 5532329 5555343 5613192 5612206 5534442 5517242 5545569 5580857 5603962 5601129 5576165 5536940 5509927 5524124 5568768 5589638 5522248 5457832 5508810 5540450 5580309 5559757 5550760 5487070 5527526 5546419 5601791 5599251 5539289 5508574 5530531 5560088 5587798 5591832 5554727 5513485 5499012 5522649 5565621 5583947 5528875 5486576 5521793 5554357 5604044 5596397 5535555 5518858 5528663 5548376 5609787 5608842 5563226 5518279 5538587 5571457 5592654 5596433 5550376 5497134 5479310 5511588 5580382 5570773 5541266 5497204 5522836 5550063 5598660 5582636 5539222 5521155 5537766 5565050 5621022 5620361 5570765 5537445 5536783 5566670 5595280 5593929 5548221 5495904 5490493 5517472 5592833 5595387 5552192 5512392
average_num_operations_total=5551529
stdev_num_operations_total=3819
sum_num_operations_total=532946806
min_num_operations_total=5457832
max_num_operations_total=5621022
first_time_thread_terminate_by_thread=10000145 10000144 10000144 10000144 10000144 10000146 10000144 10000145 10000145 10000145 10000145 10000146 10000148 10000146 10000146 10000144 10000145 10000146 10000146 10000146 10000144 10000145 10000159 10000146 10000145 10000144 10000144 10000144 10000144 10000146 10000144 10000145 10000145 10000145 10000146 10000146 10000147 10000146 10000144 10000144 10000145 10000145 10000146 10000144 10000144 10000147 10000146 10000146 10000145 10000145 10000146 10000146 10000147 10000144 10000146 10000147 10000147 10000147 10000146 10000145 10000145 10000149 10000146 10000145 10000146 10000146 10000145 10000144 10000147 10000147 10000145 10000146 10000145 10000145 10000145 10000146 10000147 10000144 10000146 10000145 10000144 10000144 10000146 10000145 10000145 10000145 10000146 10000144 10000144 10000145 10000145 10000144 10000147 10000151 10000145 10000144
min_time_thread_terminate_total=10000144
max_time_thread_terminate_total=10000159
first_time_thread_start_by_thread=3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 4 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=99868603307492
threads_final_size=9986584
final_keysum=99868603307492
final_size=9986584
validate_result=success
Validation OK.
Structural validation OK.

total_find=479658450
total_rq=0
total_inserts=26640249
total_deletes=26648107
total_updates=53288356
total_queries=479658450
total_ops=532946806
find_throughput=47965845
rq_throughput=0
update_throughput=5328835
query_throughput=47965845
total_throughput=53294680


total find                    : 479658450
total rq                      : 0
total inserts                 : 26640249
total deletes                 : 26648107
total updates                 : 53288356
total queries                 : 479658450
total ops                     : 532946806
find throughput               : 47965845
rq throughput                 : 0
update throughput             : 5328835
query throughput              : 47965845
total throughput              : 53294680

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=27.7884
PAPI_L3_TCM=10.1724
PAPI_TOT_CYC=5016.39
PAPI_TOT_INS=1379.65
garbage=2383606402686553
total_execution_walltime=12.868s
REALTIME_MAIN_END_PERF_FORMAT=6984275.379498894
[time_cmd_output] time_elapsed_sec=13.04, faults_major=0, faults_minor=238120, mem_maxresident_kb=950084, user_cputime=1226.10, sys_cputime=4.43, percent_cpu=9434%
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