__step=000385
__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 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=data000385.txt
__path_data=data/data000385.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=211469.621802065
binary=./natarajan_ext_bst_lf.debra
parsed custom binding: 0-63,128-191,64-127,192-255
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=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=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,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.018s
prefilling_size=7581072 prefilling_elapsed_millis=1s
prefilling_size=7831981 prefilling_elapsed_millis=1.101s
prefilling_size=8052633 prefilling_elapsed_millis=1.201s
prefilling_size=8246367 prefilling_elapsed_millis=1.301s
prefilling_size=8419408 prefilling_elapsed_millis=1.401s
prefilling_size=8572284 prefilling_elapsed_millis=1.501s
prefilling_size=8706776 prefilling_elapsed_millis=1.601s
prefilling_size=8828183 prefilling_elapsed_millis=1.701s
prefilling_size=8938076 prefilling_elapsed_millis=1.801s
prefilling_size=9037267 prefilling_elapsed_millis=1.901s
prefilling_size=9124993 prefilling_elapsed_millis=2.002s
prefilling_size=9205054 prefilling_elapsed_millis=2.102s
prefilling_size=9274269 prefilling_elapsed_millis=2.202s
prefilling_size=9339344 prefilling_elapsed_millis=2.302s
prefilling_size=9397939 prefilling_elapsed_millis=2.402s
prefilling_size=9451938 prefilling_elapsed_millis=2.502s
prefilling_size=9498960 prefilling_elapsed_millis=2.602s
prefilling_size=9544227 prefilling_elapsed_millis=2.702s
prefilling_size=9582404 prefilling_elapsed_millis=2.802s
prefilling_size=9617507 prefilling_elapsed_millis=2.903s
prefilling_size=9649562 prefilling_elapsed_millis=3.003s
prefilling_size=9679997 prefilling_elapsed_millis=3.104s
prefilling_size=9706457 prefilling_elapsed_millis=3.204s
prefilling_size=9731756 prefilling_elapsed_millis=3.304s
prefilling_size=9753505 prefilling_elapsed_millis=3.404s
prefilling_size=9774586 prefilling_elapsed_millis=3.504s
prefilling_size=9794229 prefilling_elapsed_millis=3.604s
prefilling_size=9811713 prefilling_elapsed_millis=3.704s
finished prefilling to size 9811705 for expected size 10000000 keysum=98128259288907, performing 86778982 updates; total_prefilling_elapsed_ms=3805 ms)
pref_size=9811705
pref_millis=3805
prefill_elapsed_ms=3825
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=2312
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=211473.476331534
REALTIME_END_PERF_FORMAT=211483.476406155
###############################################################################
################################## 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 (256 threads)...
partitioned into 1994 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.256s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 507 997 1926 3688 6885 12491 21983 37180 60387 93812 139486 197538 267134 346404 429643 511886 588908 654620 701659 727143 729439 708568 666238 606133 532015 452314 372827 296721 228212 169604 122597 85939 57908 38117 24204 15164 9339 5591 3285 1852 1095 579 306 166 84 39 21 5 2 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 3 17 68 164 491 1279 2999 6786 13973 26962 48138 81434 127942 187864 263165 347400 434864 523196 607581 676175 724847 750310 750898 726343 680251 611716 531801 448933 365230 286820 216611 159255 113970 77699 52030 33244 20989 13087 7897 4718 2609 1611 852 446 248 129 57 37 8 3 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 510 1014 1994 3852 7376 13770 24982 43966 74360 120774 187624 278972 395076 534268 692808 859286 1023772 1177816 1309240 1403318 1454286 1458878 1417136 1332476 1212266 1064030 904628 745654 593442 456424 339208 245194 171878 115816 76234 48408 30328 18678 11182 6570 3704 2190 1158 612 332 168 78 42 10 4 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 1 3 17 68 164 491 1279 2999 6786 13973 26962 48138 81434 127942 187864 263165 347400 434864 523196 607581 676175 724847 750310 750898 726343 680251 611716 531801 448933 365230 286820 216611 159255 113970 77699 52030 33244 20989 13087 7897 4718 2609 1611 852 446 248 129 57 37 8 3 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99609 1.99412 1.98323 1.9659 1.95742 1.93343 1.90712 1.87995 1.84565 1.81209 1.77676 1.74343 1.70809 1.67616 1.64837 1.62015 1.59571 1.57523 1.55579 1.53593 1.51816 1.50158 1.48569 1.47013 1.45489 1.43886 1.4251 1.41213 1.39793 1.38456 1.37159 1.36142 1.35049 1.33691 1.32912 1.3175 1.31325 1.30793 1.29934 1.29378 1.28189 1.29563 1.26438 1.26425 1.27124 1.25301 1.23214 1.26923 1.11905 1.2 1.25 1
tree_stats_height=60
tree_stats_numInternals=9933152
tree_stats_numLeaves=9933153
tree_stats_numNodes=19866305
tree_stats_numKeys=9933153
tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=30.7912
tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16320 32448 63808 123264 236032 440640 799424 1406912 2379520 3864768 6003968 8927104 12642432 17096576 22169856 27497152 32760704 37690112 41895680 44906176 46537152 46684096 45348352 42639232 38792512 34048960 28948096 23860928 18990144 14605568 10854656 7846208 5500096 3706112 2439488 1549056 970496 597696 357824 210240 118528 70080 37056 19584 10624 5376 2496 1344 320 128 64
tree_stats_sizeInBytes=635721760
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=8673
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:5 4:69 8:5908 16:214955 32:185534 64:499 128:6
(2^01, 2^02]: 5
(2^02, 2^03]: 69
(2^03, 2^04]: 5908
(2^04, 2^05]: 214955
(2^05, 2^06]: 185534
(2^06, 2^07]: 499
(2^07, 2^08]: 6
sum_limbo_reclamation_event_size_total=12428716
count_limbo_reclamation_event_size_by_thread=6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359 6359
count_limbo_reclamation_event_size_total=406976
sum_limbo_reclamation_event_count_total=407104
first_thread_announced_epoch_by_thread=17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346 17346
sum_num_inserts_by_thread=197535 197232 196498 195886 196432 197564 196599 195351 196102 196077 196432 195889 194362 196133 193807 195104 196660 197398 197656 196993 196091 197810 195922 196008 195771 195735 195133 194752 194590 194828 195059 196600 197762 197379 197704 197889 197813 197598 196821 196629 195551 194429 194834 195814 195602 198529 196712 197112 196035 197560 195354 196560 195246 195118 195299 195171 195068 195595 193567 193279 196963 197929 195908 196904
sum_num_inserts_total=12553743
sum_num_deletes_by_thread=197312 197186 196977 194960 196460 197683 195784 196172 196936 196609 195976 194609 194023 195964 193862 194326 196228 197511 197668 196560 197539 197030 197219 195705 194850 195499 195675 193959 195071 194960 194912 196350 197947 197152 197852 198197 198323 197116 196555 195439 195282 195287 195119 196010 195722 198978 196458 197848 196970 196942 195875 196497 195215 195620 194635 194945 194975 195123 193765 193991 197109 197858 196172 197108
sum_num_deletes_total=12553660
sum_num_searches_by_thread=3560307 3556690 3538421 3523370 3542342 3550608 3531682 3526292 3535539 3530648 3525471 3513185 3503081 3516581 3489882 3490272 3528915 3558396 3558137 3539452 3550353 3547099 3539924 3537856 3531491 3518531 3518635 3502286 3506985 3507182 3509796 3538223 3560588 3558321 3563970 3561033 3555574 3554070 3533776 3520487 3519081 3519277 3504915 3517545 3509868 3570248 3540482 3553066 3535529 3548075 3526017 3539847 3520140 3527003 3510566 3510520 3515600 3513371 3486662 3485497 3542666 3558377 3535420 3554788
sum_num_searches_total=225980011
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3955154 3951108 3931896 3914216 3935234 3945855 3924065 3917815 3928577 3923334 3917879 3903683 3891466 3908678 3877551 3879702 3921803 3953305 3953461 3933005 3943983 3941939 3933065 3929569 3922112 3909765 3909443 3890997 3896646 3896970 3899767 3931173 3956297 3952852 3959526 3957119 3951710 3948784 3927152 3912555 3909914 3908993 3894868 3909369 3901192 3967755 3933652 3948026 3928534 3942577 3917246 3932904 3910601 3917741 3900500 3900636 3905643 3904089 3873994 3872767 3936738 3954164 3927500 3948800
average_num_operations_total=3923240
stdev_num_operations_total=2941
sum_num_operations_total=251087414
min_num_operations_total=3872767
max_num_operations_total=3967755
first_time_thread_terminate_by_thread=10000076 10000076 10000076 10000076 10000078 10000078 10000078 10000078 10000078 10000078 10000078 10000078 10000075 10000077 10000075 10000075 10000075 10000074 10000075 10000074 10000077 10000077 10000077 10000077 10000076 10000076 10000076 10000076 10000075 10000075 10000075 10000075 10000078 10000078 10000078 10000078 10000078 10000074 10000074 10000074 10000076 10000074 10000074 10000074 10000076 10000077 10000076 10000077 10000077 10000077 10000077 10000077 10000077 10000077 10000077 10000077 10000077 10000077 10000077 10000077 10000076 10000077 10000076 10000076
min_time_thread_terminate_total=10000074
max_time_thread_terminate_total=10000078
first_time_thread_start_by_thread=6 6 6 6 6 6 6 6 7 7 7 7 8 8 8 8 9 8 9 9 8 8 8 8 6 6 6 6 8 8 8 8 5 5 5 5 3 3 3 3 4 4 4 4 4 4 4 4 5 5 5 5 4 4 4 4 7 7 7 7 7 7 7 7
min_time_thread_start_total=3
max_time_thread_start_total=9
sum_duration_all_ops_total=
threads_final_keysum=99339548815562
threads_final_size=9933153
final_keysum=99339548815562
final_size=9933153
validate_result=success
Validation OK.
Structural validation OK.
total_find=225980011
total_rq=0
total_inserts=12553743
total_deletes=12553660
total_updates=25107403
total_queries=225980011
total_ops=251087414
find_throughput=22598001
rq_throughput=0
update_throughput=2510740
query_throughput=22598001
total_throughput=25108741
total find : 225980011
total rq : 0
total inserts : 12553743
total deletes : 12553660
total updates : 25107403
total queries : 225980011
total ops : 251087414
find throughput : 22598001
rq throughput : 0
update throughput : 2510740
query throughput : 22598001
total throughput : 25108741
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=769115968437996
total_execution_walltime=14.167s
REALTIME_MAIN_END_PERF_FORMAT=211483.789411984
[time_cmd_output] time_elapsed_sec=14.31, faults_major=0, faults_minor=235152, mem_maxresident_kb=941972, user_cputime=917.10, sys_cputime=6.99, percent_cpu=6455%
timeout=false
algorithm=natarajan_ext_bst_lf.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1