__step=000386
__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 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000386.txt
__path_data=data/data000386.txt
__hostname=jax
sizeof(kcasdesc_t)=2304
sizeof(rdcssdesc_t)=192
addrof(kcasDescriptors[0])=0x555fc375d400
addrof(kcasDescriptors[1])=0x555fc375dd00
addrof(rdcssDescriptors[0])=0x555fc387d400
addrof(rdcssDescriptors[1])=0x555fc387d4c0
sizeof(validationSet)=1608
REALTIME_MAIN_START_PERF_FORMAT=6999463.475713373
binary=./sigouin_int_bst_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_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=2000000
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.006s
prefilling_size=999305 prefilling_elapsed_millis=1.001s
finished prefilling to size 999286 for expected size 1000000 keysum=999373345224, performing 37698317 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=999286
pref_millis=1102
prefill_elapsed_ms=1126
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6999464.782571621
REALTIME_END_PERF_FORMAT=6999474.782712482

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

tree_stats_computeWalltime=0.037s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 62 124 242 463 850 1469 2413 3867 5868 8544 12138 16552 21978 28137 34678 41132 47050 51513 53680 53832 51106 46726 40854 34517 27813 21664 16157 11420 7836 5029 3081 1769 986 539 286 140 63 22 12 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 1 0 3 10 41 128 302 509 1031 1731 2642 4158 5929 8560 11602 15170 18819 22622 26100 28187 29759 29196 27659 24764 21739 17671 14097 10903 7726 5550 3639 2280 1303 737 419 226 114 51 17 11 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 63 124 245 473 891 1597 2715 4376 6899 10275 14780 20710 27907 36697 46280 56302 65869 74135 79780 82019 80865 75922 68513 59281 49552 39335 30254 22323 15562 10579 6720 4049 2289 1276 705 366 177 73 29 13 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 63 124 245 473 891 1597 2715 4376 6899 10275 14780 20710 27907 36697 46280 56302 65869 74135 79780 82019 80865 75922 68513 59281 49552 39335 30254 22323 15562 10579 6720 4049 2289 1276 705 366 177 73 29 13 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 1.96875 1.98413 1.97581 1.94286 1.90486 1.83838 1.78021 1.72302 1.69287 1.63879 1.60691 1.57997 1.54829 1.52743 1.4944 1.46724 1.43936 1.41119 1.38129 1.35521 1.32959 1.30688 1.28697 1.26896 1.25362 1.23252 1.21838 1.20381 1.18555 1.17626 1.15984 1.14405 1.12843 1.12669 1.13009 1.11348 1.10109 1.0565 1.09589 1.03448 1 1

tree_stats_height=47
tree_stats_numInternals=654677
tree_stats_numLeaves=345408
tree_stats_numNodes=1000085
tree_stats_numKeys=1000085

tree_stats_avgDegreeInternal=1.5276
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34538
tree_stats_avgKeyDepth=25.0719


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:60 2:1549 4:52019 8:372814 16:53413 32:45
    [2^00, 2^01]: 60
    (2^01, 2^02]: 1549
    (2^02, 2^03]: 52019
    (2^03, 2^04]: 372814
    (2^04, 2^05]: 53413
    (2^05, 2^06]: 45
sum_limbo_reclamation_event_size_total=5481926
count_limbo_reclamation_event_size_by_thread=9997 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998
count_limbo_reclamation_event_size_total=479900
sum_limbo_reclamation_event_count_total=997440
first_thread_announced_epoch_by_thread=45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042 45042
sum_num_inserts_by_thread=467942 474141 475098 477194 473026 469304 470147 474346 482048 480648 476688 471286 471919 474219 478461 479382 475578 472932 469695 474656 475720 478481 474251 469208 470438 471757 476896 477393 475349 469199 472877 475895 480001 481724 475616 473780 472697 476139 478460 477317 474366 471806 472062 474230 477057 478623 474524 468985
sum_num_inserts_total=22783561
sum_num_deletes_by_thread=467839 472986 477351 478661 473549 469105 470974 473842 481534 482019 474800 471410 470418 474054 478348 478921 475609 473303 469487 474023 476699 477311 475173 469649 469926 470917 476106 479184 474644 469249 472271 475196 480232 482856 477113 473348 471142 476687 478857 477141 474879 472899 471405 473666 477933 479502 472921 469916
sum_num_deletes_total=22785055
sum_num_searches_by_thread=8431006 8525897 8571201 8588134 8523428 8445517 8478022 8532257 8659423 8671332 8560106 8494441 8484702 8534169 8628089 8619760 8558464 8498307 8456461 8532836 8570120 8598957 8533406 8439814 8448110 8498024 8581439 8603903 8553461 8460087 8502587 8564888 8644960 8671932 8578114 8522859 8501395 8555576 8611771 8609000 8539811 8505792 8482647 8529939 8589613 8631386 8526986 8445315
sum_num_searches_total=410095444
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=9366787 9473024 9523650 9543989 9470003 9383926 9419143 9480445 9623005 9633999 9511594 9437137 9427039 9482442 9584898 9578063 9509651 9444542 9395643 9481515 9522539 9554749 9482830 9378671 9388474 9440698 9534441 9560480 9503454 9398535 9447735 9515979 9605193 9636512 9530843 9469987 9445234 9508402 9569088 9563458 9489056 9450497 9426114 9477835 9544603 9589511 9474431 9384216
average_num_operations_total=9493001
stdev_num_operations_total=10402
sum_num_operations_total=455664060
min_num_operations_total=9366787
max_num_operations_total=9636512
first_time_thread_terminate_by_thread=10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000140 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000140 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000140 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000139 10000140 10000139 10000139 10000139
min_time_thread_terminate_total=10000139
max_time_thread_terminate_total=10000140
first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 4 4 4 3 4 4 3 4 4 4 3 4 4 3 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 3 4 4 4 3 4 4 3 4 4
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=1000250370717
threads_final_size=1000085
final_keysum=1000250370717
final_size=1000085
validate_result=success
Validation OK.
Structural validation OK.

total_find=410095444
total_rq=0
total_inserts=22783561
total_deletes=22785055
total_updates=45568616
total_queries=410095444
total_ops=455664060
find_throughput=41009544
rq_throughput=0
update_throughput=4556861
query_throughput=41009544
total_throughput=45566406


total find                    : 410095444
total rq                      : 0
total inserts                 : 22783561
total deletes                 : 22785055
total updates                 : 45568616
total queries                 : 410095444
total ops                     : 455664060
find throughput               : 41009544
rq throughput                 : 0
update throughput             : 4556861
query throughput              : 41009544
total throughput              : 45566406

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=16.1422
PAPI_L3_TCM=2.17833
PAPI_TOT_CYC=2930.87
PAPI_TOT_INS=1387.55
garbage=205092676671078
total_execution_walltime=11.437s
REALTIME_MAIN_END_PERF_FORMAT=6999474.926147797
[time_cmd_output] time_elapsed_sec=11.58, faults_major=0, faults_minor=91514, mem_maxresident_kb=368340, user_cputime=563.14, sys_cputime=1.82, percent_cpu=4876%
timeout=false
algorithm=sigouin_int_bst_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