__step=000393
__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" ./drachsler_pext_bst_lock.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=data000393.txt
__path_data=data/data000393.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=211572.384698695
binary=./drachsler_pext_bst_lock.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=drachsler_pext_bst_lock
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=192
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.022s
prefilling_size=7378784 prefilling_elapsed_millis=1s
prefilling_size=7660165 prefilling_elapsed_millis=1.101s
prefilling_size=7905292 prefilling_elapsed_millis=1.201s
prefilling_size=8121834 prefilling_elapsed_millis=1.301s
prefilling_size=8315983 prefilling_elapsed_millis=1.401s
prefilling_size=8488179 prefilling_elapsed_millis=1.501s
prefilling_size=8641801 prefilling_elapsed_millis=1.601s
prefilling_size=8776979 prefilling_elapsed_millis=1.701s
prefilling_size=8900241 prefilling_elapsed_millis=1.801s
prefilling_size=9010817 prefilling_elapsed_millis=1.901s
prefilling_size=9108627 prefilling_elapsed_millis=2.002s
prefilling_size=9196414 prefilling_elapsed_millis=2.102s
prefilling_size=9276399 prefilling_elapsed_millis=2.202s
prefilling_size=9347217 prefilling_elapsed_millis=2.302s
prefilling_size=9409889 prefilling_elapsed_millis=2.402s
prefilling_size=9467067 prefilling_elapsed_millis=2.502s
prefilling_size=9519788 prefilling_elapsed_millis=2.602s
prefilling_size=9566897 prefilling_elapsed_millis=2.702s
prefilling_size=9608330 prefilling_elapsed_millis=2.802s
prefilling_size=9645219 prefilling_elapsed_millis=2.903s
prefilling_size=9679695 prefilling_elapsed_millis=3.003s
prefilling_size=9712419 prefilling_elapsed_millis=3.103s
prefilling_size=9741056 prefilling_elapsed_millis=3.203s
prefilling_size=9765368 prefilling_elapsed_millis=3.304s
prefilling_size=9789279 prefilling_elapsed_millis=3.404s
prefilling_size=9811115 prefilling_elapsed_millis=3.504s
finished prefilling to size 9811127 for expected size 10000000 keysum=98113206247496, performing 79847650 updates; total_prefilling_elapsed_ms=3605 ms)
pref_size=9811127
pref_millis=3605
prefill_elapsed_ms=3618
6node_tIxPvE_object_size=192
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=2050
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=211576.59355484
REALTIME_END_PERF_FORMAT=211586.59425265
###############################################################################
################################## 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 1972 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.2s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 507 1000 1939 3648 6582 11424 18961 30187 46590 68658 97365 132830 175317 223684 277322 332177 384602 430018 464774 482904 483624 467547 436792 393730 343407 290049 238583 188787 144750 107717 77581 54306 36657 24051 15126 9224 5451 2967 1504 759 331 152 62 17 8 2 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 9 33 130 386 967 2129 4237 7446 13150 21508 33453 48404 67745 90610 118597 148556 179905 209166 236376 254142 262937 261528 251597 232899 207850 178469 150300 121416 94523 71498 52179 37276 25497 16921 10797 6644 4082 2306 1165 613 250 115 61 11 9 1 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 507 1009 1972 3778 6968 12391 21090 34424 54036 81808 118873 166283 223721 291429 367932 450774 533158 609923 673940 719280 737766 730484 698320 645327 576306 497899 417052 339087 266166 202240 149079 106485 73933 49548 32047 20021 12095 7049 3810 1924 944 402 177 78 19 11 2 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 507 1009 1972 3778 6968 12391 21090 34424 54036 81808 118873 166283 223721 291429 367932 450774 533158 609923 673940 719280 737766 730484 698320 645327 576306 497899 417052 339087 266166 202240 149079 106485 73933 49548 32047 20021 12095 7049 3810 1924 944 402 177 78 19 11 2 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.98047 1.99014 1.96333 1.93256 1.87877 1.83367 1.78008 1.73319 1.6928 1.65175 1.61382 1.57976 1.5466 1.519 1.49497 1.47142 1.44586 1.42262 1.39992 1.37764 1.35433 1.3346 1.31592 1.29862 1.28292 1.26807 1.25508 1.24099 1.2282 1.21599 1.20452 1.19388 1.18432 1.17436 1.16138 1.15274 1.1434 1.13212 1.11959 1.11024 1.09615 1.07521 1.06219 1.0904 1.02564 1.15789 1 1 1
tree_stats_height=57
tree_stats_numInternals=6514185
tree_stats_numLeaves=3427894
tree_stats_numNodes=9942079
tree_stats_numKeys=9942079
tree_stats_avgDegreeInternal=1.52622
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34479
tree_stats_avgKeyDepth=29.3363
tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 49152 97344 193728 378624 725376 1337856 2379072 4049280 6609408 10374912 15707136 22823616 31926336 42954432 55954368 70642944 86548608 102366336 117105216 129396480 138101760 141651072 140252928 134077440 123902784 110650752 95596608 80073984 65104704 51103872 38830080 28623168 20445120 14195136 9513216 6153024 3844032 2322240 1353408 731520 369408 181248 77184 33984 14976 3648 2112 384 192
tree_stats_sizeInBytes=1908879168
6node_tIxPvE_object_size=192
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=8077
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:2 2:63 4:5830 8:205546 16:173748 32:411
[2^00, 2^01]: 2
(2^01, 2^02]: 63
(2^02, 2^03]: 5830
(2^03, 2^04]: 205546
(2^04, 2^05]: 173748
(2^05, 2^06]: 411
sum_limbo_reclamation_event_size_total=5867579
count_limbo_reclamation_event_size_by_thread=6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025 6025
count_limbo_reclamation_event_size_total=385600
sum_limbo_reclamation_event_count_total=385728
first_thread_announced_epoch_by_thread=16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154 16154
sum_num_inserts_by_thread=186525 186833 185812 185627 186462 186393 187905 186513 187122 186557 186429 185850 185917 185821 184858 185197 184509 184115 184337 184456 186174 186618 185859 186582 184750 185657 185621 184575 184564 184487 183304 183119 185705 185613 188196 187872 187220 186725 186177 186455 187029 185740 183972 184765 184992 185680 184554 183936 186188 185021 185089 185849 185507 185884 185134 184265 184201 184575 182725 183144 183945 183518 183629 185687
sum_num_inserts_total=11867540
sum_num_deletes_by_thread=186603 187700 186103 186350 186531 185858 187194 186370 187276 186628 185745 186149 185694 185124 184560 184680 184473 184650 184088 184464 186394 186113 186032 185981 185813 185111 185685 184435 183898 184251 182957 183679 185657 186161 187568 186949 187584 187684 186778 187778 187402 186435 185089 184928 184581 185689 184564 184528 186225 186004 185896 185028 185117 185250 185707 184812 183877 183356 182753 182570 185114 184279 183965 186358
sum_num_deletes_total=11872275
sum_num_searches_by_thread=3357025 3367457 3352712 3343569 3353133 3347758 3365955 3354497 3362853 3360191 3347902 3349881 3345949 3348573 3320932 3323786 3324643 3320384 3319245 3316259 3356395 3349979 3347806 3338408 3337248 3334672 3332210 3318513 3317367 3310159 3301065 3306322 3344361 3341697 3366330 3372570 3378181 3367402 3358146 3365204 3364564 3356242 3327594 3331309 3329748 3331759 3325180 3315462 3354093 3339824 3338684 3339173 3335781 3333625 3332974 3314047 3313783 3313906 3299203 3297228 3315355 3312437 3309266 3349471
sum_num_searches_total=213607447
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3730153 3741990 3724627 3715546 3726126 3720009 3741054 3727380 3737251 3733376 3720076 3721880 3717560 3719518 3690350 3693663 3693625 3689149 3687670 3685179 3728963 3722710 3719697 3710971 3707811 3705440 3703516 3687523 3685829 3678897 3667326 3673120 3715723 3713471 3742094 3747391 3752985 3741811 3731101 3739437 3738995 3728417 3696655 3701002 3699321 3703128 3694298 3683926 3726506 3710849 3709669 3710050 3706405 3704759 3703815 3683124 3681861 3681837 3664681 3662942 3684414 3680234 3676860 3721516
average_num_operations_total=3708550
stdev_num_operations_total=2844
sum_num_operations_total=237347262
min_num_operations_total=3662942
max_num_operations_total=3752985
first_time_thread_terminate_by_thread=10000076 10000076 10000076 10000076 10000071 10000072 10000071 10000071 10000072 10000072 10000072 10000071 10000074 10000074 10000074 10000074 10000072 10000073 10000072 10000072 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000074 10000074 10000074 10000074 10000074 10000074 10000074 10000074 10000073 10000073 10000073 10000072 10000075 10000075 10000075 10000075 10000072 10000073 10000073 10000073 10000075 10000075 10000075 10000075 10000073 10000073 10000073 10000073 10000075 10000075 10000075 10000075 10000074 10000074 10000074 10000074
min_time_thread_terminate_total=10000071
max_time_thread_terminate_total=10000076
first_time_thread_start_by_thread=7 7 7 7 6 6 6 6 8 8 8 8 6 6 6 6 7 7 7 7 9 9 9 9 9 9 9 9 9 9 9 9 7 7 7 7 8 8 8 8 11 11 11 11 7 7 7 7 10 10 10 10 10 10 10 10 11 11 10 11 8 8 8 8
min_time_thread_start_total=6
max_time_thread_start_total=11
sum_duration_all_ops_total=
threads_final_keysum=99431895826415
threads_final_size=9942079
final_keysum=99431895826415
final_size=9942079
validate_result=success
Validation OK.
Structural validation OK.
total_find=213607447
total_rq=0
total_inserts=11867540
total_deletes=11872275
total_updates=23739815
total_queries=213607447
total_ops=237347262
find_throughput=21360744
rq_throughput=0
update_throughput=2373981
query_throughput=21360744
total_throughput=23734726
total find : 213607447
total rq : 0
total inserts : 11867540
total deletes : 11872275
total updates : 23739815
total queries : 213607447
total ops : 237347262
find throughput : 21360744
rq throughput : 0
update throughput : 2373981
query throughput : 21360744
total throughput : 23734726
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=495187022653662
total_execution_walltime=13.937s
REALTIME_MAIN_END_PERF_FORMAT=211586.322527502
[time_cmd_output] time_elapsed_sec=14.17, faults_major=0, faults_minor=546802, mem_maxresident_kb=2188056, user_cputime=900.97, sys_cputime=6.31, percent_cpu=6401%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1