__step=000722
__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" ./natarajan_ext_bst_lf.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000722.txt
__path_data=data/data000722.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6987610.982243083
binary=./natarajan_ext_bst_lf.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=natarajan_ext_bst_lf
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=50
DEL_FRAC=50
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=50.0 50.0
PREFILL_TYPE=PREFILL_HYBRID
PREFILL_HYBRID_MIN_MS=1000
PREFILL_HYBRID_MAX_MS=5000
sizes: node=32
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.016s
prefilling_size=7226043 prefilling_elapsed_millis=1.001s
prefilling_size=7496544 prefilling_elapsed_millis=1.101s
prefilling_size=7740204 prefilling_elapsed_millis=1.201s
prefilling_size=7957082 prefilling_elapsed_millis=1.301s
prefilling_size=8148878 prefilling_elapsed_millis=1.401s
prefilling_size=8323727 prefilling_elapsed_millis=1.501s
prefilling_size=8480363 prefilling_elapsed_millis=1.602s
prefilling_size=8621072 prefilling_elapsed_millis=1.702s
prefilling_size=8749690 prefilling_elapsed_millis=1.802s
prefilling_size=8865029 prefilling_elapsed_millis=1.902s
prefilling_size=8967328 prefilling_elapsed_millis=2.002s
prefilling_size=9061838 prefilling_elapsed_millis=2.103s
prefilling_size=9147036 prefilling_elapsed_millis=2.203s
prefilling_size=9225224 prefilling_elapsed_millis=2.303s
prefilling_size=9295345 prefilling_elapsed_millis=2.403s
prefilling_size=9358654 prefilling_elapsed_millis=2.503s
prefilling_size=9415046 prefilling_elapsed_millis=2.604s
prefilling_size=9465855 prefilling_elapsed_millis=2.704s
prefilling_size=9515152 prefilling_elapsed_millis=2.804s
prefilling_size=9559046 prefilling_elapsed_millis=2.904s
prefilling_size=9596986 prefilling_elapsed_millis=3.004s
prefilling_size=9633028 prefilling_elapsed_millis=3.105s
prefilling_size=9666370 prefilling_elapsed_millis=3.205s
prefilling_size=9695290 prefilling_elapsed_millis=3.305s
prefilling_size=9722029 prefilling_elapsed_millis=3.405s
prefilling_size=9746451 prefilling_elapsed_millis=3.505s
prefilling_size=9769456 prefilling_elapsed_millis=3.606s
prefilling_size=9790320 prefilling_elapsed_millis=3.706s
prefilling_size=9808634 prefilling_elapsed_millis=3.806s
finished prefilling to size 9808670 for expected size 10000000 keysum=98073196056946, performing 79029413 updates; total_prefilling_elapsed_ms=3907 ms)
pref_size=9808670
pref_millis=3907
prefill_elapsed_ms=3923
6node_tIxPvE_object_size=32
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=3619

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6987615.102053289
REALTIME_END_PERF_FORMAT=6987625.102195208

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

tree_stats_computeWalltime=0.157s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 254 506 998 1952 3755 7071 12997 23131 39576 64995 101523 151172 214297 290144 375034 465033 553494 633344 698904 743118 763140 757864 724314 668969 595325 511881 422707 337270 258730 191264 135886 93200 61383 39084 24058 14214 8013 4540 2359 1102 459 180 72 19 6 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 0 2 14 44 149 439 1145 2863 6686 14157 28467 51874 88047 138450 205254 285035 376572 473644 567784 654690 723096 768416 791414 779659 742613 678769 601055 508144 415810 326196 246642 178572 125017 83682 54110 33902 20415 11486 6721 3616 1745 738 288 125 32 12
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 254 508 1012 1996 3904 7510 14142 25994 46262 79152 129990 203046 302344 428594 580288 750068 930066 1106988 1266688 1397808 1486236 1526280 1515728 1448628 1337938 1190650 1023762 845414 674540 517460 382528 271772 186400 122766 78168 48116 28428 16026 9080 4718 2204 918 360 144 38 12
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 1 0 2 14 44 149 439 1145 2863 6686 14157 28467 51874 88047 138450 205254 285035 376572 473644 567784 654690 723096 768416 791414 779659 742613 678769 601055 508144 415810 326196 246642 178572 125017 83682 54110 33902 20415 11486 6721 3616 1745 738 288 125 32 12
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 2 1.99606 1.98617 1.97796 1.96183 1.94154 1.91904 1.88986 1.85548 1.82114 1.78101 1.74452 1.70879 1.67697 1.64629 1.61999 1.59511 1.57213 1.55176 1.53163 1.51347 1.49654 1.47787 1.46179 1.44496 1.42992 1.4129 1.39894 1.38357 1.36962 1.35523 1.34293 1.32931 1.31836 1.30777 1.29541 1.28187 1.28329 1.2598 1.23357 1.20826 1.19608 1.2 1.13194 1.15789 1

tree_stats_height=54
tree_stats_numInternals=9997591
tree_stats_numLeaves=9997592
tree_stats_numNodes=19995183
tree_stats_numKeys=9997592

tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=30.3444

tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8128 16256 32384 63872 124928 240320 452544 831808 1480384 2532864 4159680 6497472 9675008 13715008 18569216 24002176 29762112 35423616 40534016 44729856 47559552 48840960 48503296 46356096 42814016 38100800 32760384 27053248 21585280 16558720 12240896 8696704 5964800 3928512 2501376 1539712 909696 512832 290560 150976 70528 29376 11520 4608 1216 384
tree_stats_sizeInBytes=639845856

6node_tIxPvE_object_size=32
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=11992


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:0 16:0 32:0 64:10 128:377169 256:24629
    (2^06, 2^07]: 10
    (2^07, 2^08]: 377169
    (2^08, 2^09]: 24629
sum_limbo_reclamation_event_size_total=90426480
count_limbo_reclamation_event_size_by_thread=8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371 8371
count_limbo_reclamation_event_size_total=401808
sum_limbo_reclamation_event_count_total=401904
first_thread_announced_epoch_by_thread=23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984 23984
sum_num_inserts_by_thread=1878647 1886549 1898421 1897281 1890808 1874651 1880825 1888260 1906800 1904971 1885267 1877636 1882969 1893255 1904336 1902286 1894839 1882593 1873724 1883458 1891951 1898190 1880822 1870671 1878939 1884723 1899058 1896300 1888826 1873838 1879114 1887222 1906226 1905648 1884227 1879338 1883700 1892906 1899884 1902970 1894634 1879027 1872954 1880615 1890301 1898989 1883453 1868215
sum_num_inserts_total=90640317
sum_num_deletes_by_thread=1875831 1887440 1897530 1896702 1891079 1876835 1879432 1888862 1902970 1906109 1886272 1879559 1886090 1893348 1899915 1905246 1891805 1882275 1873679 1881945 1893100 1899255 1880704 1871273 1877156 1884677 1897760 1895325 1892582 1873112 1880017 1886220 1904697 1904674 1884760 1879547 1884055 1892293 1903676 1903644 1892017 1881140 1872634 1881353 1891351 1898466 1882098 1870970
sum_num_deletes_total=90641480
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3754478 3773989 3795951 3793983 3781887 3751486 3760257 3777122 3809770 3811080 3771539 3757195 3769059 3786603 3804251 3807532 3786644 3764868 3747403 3765403 3785051 3797445 3761526 3741944 3756095 3769400 3796818 3791625 3781408 3746950 3759131 3773442 3810923 3810322 3768987 3758885 3767755 3785199 3803560 3806614 3786651 3760167 3745588 3761968 3781652 3797455 3765551 3739185
average_num_operations_total=3776704
stdev_num_operations_total=3019
sum_num_operations_total=181281797
min_num_operations_total=3739185
max_num_operations_total=3811080
first_time_thread_terminate_by_thread=10000269 10000234 10000262 10000190 10000178 10000162 10000180 10000184 10000247 10000173 10000180 10000179 10000181 10000235 10000210 10000154 10000190 10000243 10000204 10000233 10000184 10000190 10000213 10000145 10000192 10000232 10000172 10000251 10000179 10000217 10000213 10000209 10000207 10000170 10000178 10000156 10000246 10000175 10000246 10000175 10000208 10000192 10000205 10000232 10000238 10000226 10000191 10000168
min_time_thread_terminate_total=10000145
max_time_thread_terminate_total=10000269
first_time_thread_start_by_thread=6 6 6 6 6 6 6 6 6 6 6 6 5 6 6 6 5 6 6 6 6 6 5 6 6 6 6 6 6 6 6 6 6 6 6 6 5 6 6 6 5 6 6 6 6 6 5 6
min_time_thread_start_total=5
max_time_thread_start_total=6
sum_duration_all_ops_total=

threads_final_keysum=99977114835182
threads_final_size=9997592
final_keysum=99977114835182
final_size=9997592
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=90640317
total_deletes=90641480
total_updates=181281797
total_queries=0
total_ops=181281797
find_throughput=0
rq_throughput=0
update_throughput=18128179
query_throughput=0
total_throughput=18128179


total find                    : 0
total rq                      : 0
total inserts                 : 90640317
total deletes                 : 90641480
total updates                 : 181281797
total queries                 : 0
total ops                     : 181281797
find throughput               : 0
rq throughput                 : 0
update throughput             : 18128179
query throughput              : 0
total throughput              : 18128179

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=30.6832
PAPI_L3_TCM=11.0313
PAPI_TOT_CYC=7381.91
PAPI_TOT_INS=1107.46
garbage=0
total_execution_walltime=14.478s
REALTIME_MAIN_END_PERF_FORMAT=6987625.461477881
[time_cmd_output] time_elapsed_sec=14.67, faults_major=0, faults_minor=236801, mem_maxresident_kb=949108, user_cputime=699.81, sys_cputime=3.12, percent_cpu=4788%
timeout=false
algorithm=natarajan_ext_bst_lf.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2