__step=000537
__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" ./bronson_pext_bst_occ.debra -nwork 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000537.txt
__path_data=data/data000537.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=213318.488426129
binary=./bronson_pext_bst_occ.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=bronson_pext_bst_occ
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=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=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,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.015s
prefilling_size=1000325 prefilling_elapsed_millis=1s
finished prefilling to size 1000312 for expected size 1000000 keysum=1000884417132, performing 38407231 updates; total_prefilling_elapsed_ms=1101 ms)
pref_size=1000312
pref_millis=1101
prefill_elapsed_ms=1113
6node_tIxPvE_object_size=56
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=1004
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=213319.646195892
REALTIME_END_PERF_FORMAT=213329.646287960
###############################################################################
################################## 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 1024 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.077s
tree_stats_numInternalsAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32766 65141 121669 178550 164889 77127 13518 468 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 367 7659 54237 152180 189263 99137 17434 584
tree_stats_numNodesAtDepth=1 1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65508 129328 232787 317069 266390 112655 17902 584
tree_stats_numKeysAtDepth=0 1 1 1 5 10 16 30 65 125 254 509 999 2048 4119 8192 16743 35848 82658 181000 282953 255823 111487 17879 584
tree_stats_avgDegreeAtDepth=1 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99921 1.97983 1.8592 1.59504 1.3201 1.13333 1.03891 1.00642 1
tree_stats_height=25
tree_stats_numInternals=686896
tree_stats_numLeaves=520863
tree_stats_numNodes=1207759
tree_stats_numKeys=1001350
tree_stats_avgDegreeInternal=1.75829
tree_stats_avgDegreeLeaves=0.999965
tree_stats_avgDegree=1.43125
tree_stats_avgKeyDepth=19.9159
tree_stats_bytesAtDepth=56 56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3668448 7242368 13036072 17755864 14917840 6308680 1002512 32704
tree_stats_sizeInBytes=67634504
6node_tIxPvE_object_size=56
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=15032
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:156 2:3290 4:73733 8:451216 16:111230 32:136 64:31 128:62
[2^00, 2^01]: 156
(2^01, 2^02]: 3290
(2^02, 2^03]: 73733
(2^03, 2^04]: 451216
(2^04, 2^05]: 111230
(2^05, 2^06]: 136
(2^06, 2^07]: 31
(2^07, 2^08]: 62
sum_limbo_reclamation_event_size_total=7640631
count_limbo_reclamation_event_size_by_thread=9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9997 9998 9998 9997 9998 9998 9998 9997 9997 9998 9997 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9998 9997 9997 9997 9998 9997 9997 9998 9997 9997 9998 9998 9998 9997 9997 9998 9998 9997 9998 9998 9997 9998 9997 9998
count_limbo_reclamation_event_size_total=639854
sum_limbo_reclamation_event_count_total=897792
first_thread_announced_epoch_by_thread=30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064 30064
sum_num_inserts_by_thread=438250 431566 435657 430247 430653 429293 431713 428057 433917 434350 423929 430025 420504 417672 422035 416017 423907 422136 426226 431727 425570 429515 428019 429909 422795 424474 421025 419694 415096 414351 418540 417974 430175 429071 430423 430991 427123 426537 428793 419027 424770 424646 420794 421460 419924 413135 412301 427221 431867 423396 420983 426994 430714 428743 417635 420603 423509 425322 424291 423411 419814 426607 425714 424810
sum_num_inserts_total=27205647
sum_num_deletes_by_thread=439287 431609 436302 430836 429324 428690 434553 428208 434414 433266 423598 429049 420247 417863 422261 415962 423014 420112 426044 431057 425549 431915 429839 429494 422574 424786 421033 418416 414383 413604 418449 417131 429214 428136 431352 431872 427148 426543 429402 419097 426483 423890 421079 421728 420437 414057 411762 427420 432197 423433 421479 427257 430390 429741 419177 421437 423217 425946 424660 424747 420162 427100 426250 425533
sum_num_deletes_total=27215215
sum_num_searches_by_thread=7900181 7772226 7845897 7767900 7738966 7724950 7808115 7700487 7808365 7800283 7626067 7718784 7571033 7532959 7607949 7490794 7622070 7600776 7667596 7760150 7657248 7757386 7726108 7730038 7605541 7657408 7592921 7542441 7479893 7453371 7537592 7530124 7748766 7708188 7772612 7766532 7684042 7673471 7734287 7551792 7659174 7634041 7588320 7588779 7568502 7446401 7412373 7688010 7779694 7625168 7585749 7698986 7743528 7724574 7527727 7587048 7627779 7657666 7635496 7625026 7561953 7696308 7666956 7665267
sum_num_searches_total=489969834
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=8777718 8635401 8717856 8628983 8598943 8582933 8674381 8556752 8676696 8667899 8473594 8577858 8411784 8368494 8452245 8322773 8468991 8443024 8519866 8622934 8508367 8618816 8583966 8589441 8450910 8506668 8434979 8380551 8309372 8281326 8374581 8365229 8608155 8565395 8634387 8629395 8538313 8526551 8592482 8389916 8510427 8482577 8430193 8431967 8408863 8273593 8236436 8542651 8643758 8471997 8428211 8553237 8604632 8583058 8364539 8429088 8474505 8508934 8484447 8473184 8401929 8550015 8518920 8515610
average_num_operations_total=8506104
stdev_num_operations_total=14372
sum_num_operations_total=544390696
min_num_operations_total=8236436
max_num_operations_total=8777718
first_time_thread_terminate_by_thread=10000093 10000093 10000093 10000093 10000092 10000092 10000092 10000092 10000095 10000095 10000095 10000095 10000095 10000095 10000095 10000095 10000092 10000092 10000092 10000092 10000093 10000093 10000093 10000093 10000093 10000091 10000091 10000091 10000094 10000094 10000094 10000094 10000093 10000093 10000093 10000093 10000094 10000094 10000094 10000094 10000094 10000094 10000094 10000094 10000094 10000094 10000094 10000094 10000095 10000096 10000095 10000095 10000094 10000094 10000094 10000094 10000092 10000094 10000094 10000094 10000092 10000091 10000092 10000091
min_time_thread_terminate_total=10000091
max_time_thread_terminate_total=10000096
first_time_thread_start_by_thread=10 10 10 10 11 11 11 11 15 15 15 15 10 10 10 10 15 15 14 15 11 11 11 11 8 8 8 8 15 15 15 15 14 14 14 14 9 9 9 9 11 11 11 11 13 13 13 13 13 14 14 14 10 11 10 11 12 12 12 12 14 14 14 14
min_time_thread_start_total=8
max_time_thread_start_total=15
sum_duration_all_ops_total=
threads_final_keysum=1001749599936
threads_final_size=1001350
final_keysum=1001749599936
final_size=1001350
validate_result=success
Validation OK.
Structural validation OK.
total_find=489969834
total_rq=0
total_inserts=27205647
total_deletes=27215215
total_updates=54420862
total_queries=489969834
total_ops=544390696
find_throughput=48996983
rq_throughput=0
update_throughput=5442086
query_throughput=48996983
total_throughput=54439069
total find : 489969834
total rq : 0
total inserts : 27205647
total deletes : 27215215
total updates : 54420862
total queries : 489969834
total ops : 544390696
find throughput : 48996983
rq throughput : 0
update throughput : 5442086
query throughput : 48996983
total throughput : 54439069
elapsed milliseconds : 10000
napping milliseconds overtime : 0
garbage=84065985230536
total_execution_walltime=11.329s
REALTIME_MAIN_END_PERF_FORMAT=213329.817958659
[time_cmd_output] time_elapsed_sec=11.43, faults_major=0, faults_minor=95060, mem_maxresident_kb=381132, user_cputime=728.90, sys_cputime=5.25, percent_cpu=6418%
timeout=false
algorithm=bronson_pext_bst_occ.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1