__step=000729
__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 50.0 50.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000729.txt
__path_data=data/data000729.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=215633.475678052
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=50
DEL_FRAC=50
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=50.0 50.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.016s
prefilling_size=7284393 prefilling_elapsed_millis=1s
prefilling_size=7563095 prefilling_elapsed_millis=1.1s
prefilling_size=7808806 prefilling_elapsed_millis=1.201s
prefilling_size=8024859 prefilling_elapsed_millis=1.301s
prefilling_size=8217074 prefilling_elapsed_millis=1.401s
prefilling_size=8390025 prefilling_elapsed_millis=1.501s
prefilling_size=8544323 prefilling_elapsed_millis=1.601s
prefilling_size=8682625 prefilling_elapsed_millis=1.701s
prefilling_size=8806981 prefilling_elapsed_millis=1.801s
prefilling_size=8919165 prefilling_elapsed_millis=1.901s
prefilling_size=9018629 prefilling_elapsed_millis=2.002s
prefilling_size=9110598 prefilling_elapsed_millis=2.102s
prefilling_size=9191270 prefilling_elapsed_millis=2.202s
prefilling_size=9265166 prefilling_elapsed_millis=2.302s
prefilling_size=9331788 prefilling_elapsed_millis=2.402s
prefilling_size=9391747 prefilling_elapsed_millis=2.502s
prefilling_size=9446798 prefilling_elapsed_millis=2.602s
prefilling_size=9495894 prefilling_elapsed_millis=2.702s
prefilling_size=9539912 prefilling_elapsed_millis=2.802s
prefilling_size=9579899 prefilling_elapsed_millis=2.903s
prefilling_size=9617171 prefilling_elapsed_millis=3.003s
prefilling_size=9651542 prefilling_elapsed_millis=3.103s
prefilling_size=9681895 prefilling_elapsed_millis=3.203s
prefilling_size=9710284 prefilling_elapsed_millis=3.303s
prefilling_size=9736299 prefilling_elapsed_millis=3.404s
prefilling_size=9759262 prefilling_elapsed_millis=3.504s
prefilling_size=9780343 prefilling_elapsed_millis=3.604s
prefilling_size=9799817 prefilling_elapsed_millis=3.704s
prefilling_size=9817371 prefilling_elapsed_millis=3.804s
finished prefilling to size 9817391 for expected size 10000000 keysum=98181938826779, performing 85882485 updates; total_prefilling_elapsed_ms=3905 ms)
pref_size=9817391
pref_millis=3905
prefill_elapsed_ms=3925
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=2205

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=215637.429699180
REALTIME_END_PERF_FORMAT=215647.429767585

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

tree_stats_computeWalltime=0.129s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 510 991 1924 3695 6840 12413 21815 36783 59103 91109 134451 189078 252999 323257 394132 459426 508912 537069 540569 522023 481907 427958 368109 304749 243273 187217 139228 100122 69424 46085 29408 18223 11175 6658 3839 2093 1087 529 232 99 33 9 1 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 14 31 84 308 718 1679 3814 7898 14889 26353 43395 67783 98510 135318 175839 218917 256060 283276 295577 297119 283034 256100 226611 191567 156713 123133 92790 68015 48324 32543 20989 13074 8086 4851 2886 1581 862 435 189 83 30 11 0 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1005 1955 3779 7148 13131 23494 40597 67001 105998 160804 232473 320782 421767 529450 635265 727829 793129 823845 817600 779026 710992 624209 531360 434840 343930 262361 192912 137439 94409 61951 39212 24249 14744 8690 4979 2668 1391 667 288 116 39 12 1 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1005 1955 3779 7148 13131 23494 40597 67001 105998 160804 232473 320782 421767 529450 635265 727829 793129 823845 817600 779026 710992 624209 531360 434840 343930 262361 192912 137439 94409 61951 39212 24249 14744 8690 4979 2668 1391 667 288 116 39 12 1 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 1.9668 1.9592 1.94885 1.91373 1.88011 1.84388 1.79944 1.74434 1.69991 1.65751 1.60957 1.56653 1.52611 1.48888 1.45544 1.42251 1.3905 1.36158 1.33627 1.31434 1.29407 1.27602 1.26153 1.24483 1.23148 1.21849 1.20462 1.19344 1.18179 1.16806 1.15825 1.15368 1.14718 1.13782 1.13119 1.11549 1.11394 1.09921 1.08396 1.05903 1.05172 1.07692 1 1 1

tree_stats_height=54
tree_stats_numInternals=6539069
tree_stats_numLeaves=3459492
tree_stats_numNodes=9998561
tree_stats_numKeys=9998561

tree_stats_avgDegreeInternal=1.52905
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.346
tree_stats_avgKeyDepth=27.7096

tree_stats_bytesAtDepth=192 384 768 1536 3072 6144 12288 24576 49152 98304 192960 375360 725568 1372416 2521152 4510848 7794624 12864192 20351616 30874368 44634816 61590144 80979264 101654400 121970880 139743168 152280768 158178240 156979200 149572992 136510464 119848128 102021120 83489280 66034560 50373312 37039104 26388288 18126528 11894592 7528704 4655808 2830848 1668480 955968 512256 267072 128064 55296 22272 7488 2304 192 192
tree_stats_sizeInBytes=1919723712

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=7524


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:2 16:2 32:1 64:8368 128:331412 256:500 512:2
    (2^03, 2^04]: 2
    (2^04, 2^05]: 2
    (2^05, 2^06]: 1
    (2^06, 2^07]: 8368
    (2^07, 2^08]: 331412
    (2^08, 2^09]: 500
    (2^09, 2^10]: 2
sum_limbo_reclamation_event_size_total=51137521
count_limbo_reclamation_event_size_by_thread=5317 5317 5317 5316 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317 5317
count_limbo_reclamation_event_size_total=340287
sum_limbo_reclamation_event_count_total=340416
first_thread_announced_epoch_by_thread=15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048 15048
sum_num_inserts_by_thread=1599565 1604906 1600310 1597081 1606196 1605693 1602701 1612550 1602732 1601306 1592286 1600919 1591533 1606119 1586836 1602536 1602784 1603574 1599798 1597846 1600437 1614092 1609723 1596345 1592992 1590657 1587342 1595824 1588232 1601156 1597872 1596479 1609092 1617603 1606693 1604211 1618822 1606831 1610666 1594997 1599184 1598927 1604207 1594748 1610805 1610394 1616055 1600108 1608337 1594624 1605678 1608634 1609160 1593364 1586525 1585477 1604506 1587757 1601621 1602722 1599166 1613121 1608395 1598992
sum_num_inserts_total=102499844
sum_num_deletes_by_thread=1599881 1605716 1599598 1598682 1608929 1606729 1600715 1611776 1605370 1602750 1594512 1602335 1593366 1604806 1586961 1600904 1604545 1605708 1599178 1597798 1600493 1612460 1610299 1598025 1593114 1591169 1587234 1594285 1589373 1601073 1596516 1593766 1609782 1620105 1605687 1606960 1620425 1607174 1611972 1597565 1597959 1597703 1603132 1595030 1608760 1609678 1617180 1601113 1611060 1594116 1605228 1603591 1606248 1593162 1587506 1586997 1603010 1587068 1600450 1601512 1600592 1612065 1610924 1597674
sum_num_deletes_total=102509494
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3199446 3210622 3199908 3195763 3215125 3212422 3203416 3224326 3208102 3204056 3186798 3203254 3184899 3210925 3173797 3203440 3207329 3209282 3198976 3195644 3200930 3226552 3220022 3194370 3186106 3181826 3174576 3190109 3177605 3202229 3194388 3190245 3218874 3237708 3212380 3211171 3239247 3214005 3222638 3192562 3197143 3196630 3207339 3189778 3219565 3220072 3233235 3201221 3219397 3188740 3210906 3212225 3215408 3186526 3174031 3172474 3207516 3174825 3202071 3204234 3199758 3225186 3219319 3196666
average_num_operations_total=3203270
stdev_num_operations_total=1990
sum_num_operations_total=205009338
min_num_operations_total=3172474
max_num_operations_total=3239247
first_time_thread_terminate_by_thread=10000070 10000070 10000070 10000070 10000072 10000072 10000073 10000072 10000069 10000069 10000069 10000070 10000072 10000072 10000072 10000072 10000071 10000071 10000071 10000071 10000068 10000068 10000068 10000076 10000072 10000072 10000072 10000072 10000070 10000070 10000070 10000070 10000068 10000070 10000070 10000068 10000071 10000071 10000086 10000071 10000069 10000069 10000070 10000069 10000071 10000071 10000071 10000071 10000069 10000069 10000069 10000069 10000070 10000070 10000070 10000070 10000068 10000068 10000085 10000068 10000070 10000071 10000070 10000070
min_time_thread_terminate_total=10000068
max_time_thread_terminate_total=10000086
first_time_thread_start_by_thread=4 4 4 4 4 4 4 4 3 3 3 3 7 7 7 7 5 5 5 5 8 8 8 8 5 5 5 5 7 7 7 7 4 4 4 4 4 4 4 4 6 6 6 6 7 7 7 7 6 6 6 6 5 5 5 5 6 5 6 6 6 6 6 6
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=100006322535323
threads_final_size=9998561
final_keysum=100006322535323
final_size=9998561
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=102499844
total_deletes=102509494
total_updates=205009338
total_queries=0
total_ops=205009338
find_throughput=0
rq_throughput=0
update_throughput=20500933
query_throughput=0
total_throughput=20500933


total find                    : 0
total rq                      : 0
total inserts                 : 102499844
total deletes                 : 102509494
total updates                 : 205009338
total queries                 : 0
total ops                     : 205009338
find throughput               : 0
rq throughput                 : 0
update throughput             : 20500933
query throughput              : 0
total throughput              : 20500933

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=0
total_execution_walltime=14.133s
REALTIME_MAIN_END_PERF_FORMAT=215647.609968708
[time_cmd_output] time_elapsed_sec=14.37, faults_major=0, faults_minor=576324, mem_maxresident_kb=2306176, user_cputime=917.34, sys_cputime=7.92, percent_cpu=6435%
timeout=false
algorithm=drachsler_pext_bst_lock.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1