__step=000754
__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" ./brown_ext_chromatic_lf.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=data000754.txt
__path_data=data/data000754.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=215956.226994579
binary=./brown_ext_chromatic_lf.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=brown_ext_chromatic_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=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=56 descriptor=128
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=5590512 prefilling_elapsed_millis=1.001s
prefilling_size=5890407 prefilling_elapsed_millis=1.101s
prefilling_size=6163197 prefilling_elapsed_millis=1.201s
prefilling_size=6415040 prefilling_elapsed_millis=1.301s
prefilling_size=6645285 prefilling_elapsed_millis=1.401s
prefilling_size=6860048 prefilling_elapsed_millis=1.501s
prefilling_size=7057740 prefilling_elapsed_millis=1.601s
prefilling_size=7242063 prefilling_elapsed_millis=1.701s
prefilling_size=7413738 prefilling_elapsed_millis=1.802s
prefilling_size=7572042 prefilling_elapsed_millis=1.902s
prefilling_size=7721035 prefilling_elapsed_millis=2.002s
prefilling_size=7859889 prefilling_elapsed_millis=2.102s
prefilling_size=7988373 prefilling_elapsed_millis=2.202s
prefilling_size=8109902 prefilling_elapsed_millis=2.302s
prefilling_size=8222646 prefilling_elapsed_millis=2.402s
prefilling_size=8328620 prefilling_elapsed_millis=2.502s
prefilling_size=8426345 prefilling_elapsed_millis=2.602s
prefilling_size=8518868 prefilling_elapsed_millis=2.703s
prefilling_size=8605296 prefilling_elapsed_millis=2.803s
prefilling_size=8685215 prefilling_elapsed_millis=2.903s
prefilling_size=8762995 prefilling_elapsed_millis=3.003s
prefilling_size=8833432 prefilling_elapsed_millis=3.103s
prefilling_size=8900200 prefilling_elapsed_millis=3.204s
prefilling_size=8963703 prefilling_elapsed_millis=3.304s
prefilling_size=9022437 prefilling_elapsed_millis=3.404s
prefilling_size=9078554 prefilling_elapsed_millis=3.504s
prefilling_size=9132014 prefilling_elapsed_millis=3.604s
prefilling_size=9181755 prefilling_elapsed_millis=3.704s
prefilling_size=9228604 prefilling_elapsed_millis=3.804s
prefilling_size=9271564 prefilling_elapsed_millis=3.904s
prefilling_size=9311887 prefilling_elapsed_millis=4.005s
prefilling_size=9349167 prefilling_elapsed_millis=4.105s
prefilling_size=9385677 prefilling_elapsed_millis=4.205s
prefilling_size=9420292 prefilling_elapsed_millis=4.305s
prefilling_size=9452067 prefilling_elapsed_millis=4.405s
prefilling_size=9483612 prefilling_elapsed_millis=4.505s
prefilling_size=9510853 prefilling_elapsed_millis=4.605s
prefilling_size=9537255 prefilling_elapsed_millis=4.705s
prefilling_size=9562479 prefilling_elapsed_millis=4.805s
prefilling_size=9585960 prefilling_elapsed_millis=4.906s
prefilling_size=9609319 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 390671 keys with 64 threads...
tid=0 thread_prefill_amount=137500 percent_done_estimate=2252.5 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=140625 percent_done_estimate=2303.7 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0578079s
prefilling_size=10000000 prefilling_elapsed_millis=0.06s
finished prefilling to size 10000000 for expected size 10000000 keysum=100023906567154, performing 67964714 updates; total_prefilling_elapsed_ms=60 ms)
pref_size=10000000
pref_millis=60
prefill_elapsed_ms=61
4NodeIxPvE_object_size=56
4NodeIxPvE_allocated_count=0
4NodeIxPvE_allocated_size=0MB
4NodeIxPvE_get_from_pool=0
4NodeIxPvE_deallocated=0
4NodeIxPvE_limbo_count=44648
4NodeIxPvE_limbo_details=15242 15494 13912 

global_epoch_counter=2398

9SCXRecordIxPvE_object_size=128
9SCXRecordIxPvE_allocated_count=0
9SCXRecordIxPvE_allocated_size=0MB
9SCXRecordIxPvE_get_from_pool=0
9SCXRecordIxPvE_deallocated=0
9SCXRecordIxPvE_limbo_count=21318
9SCXRecordIxPvE_limbo_details=7293 7472 6553 

global_epoch_counter=0

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=215961.450872430
REALTIME_END_PERF_FORMAT=215971.450939809

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

tree_stats_computeWalltime=0.057s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65525 130825 258593 493199 866375 1330814 1715794 1806266 1515199 993720 500187 188937 52532 10457 1444 129 4 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 11 225 3057 23987 120023 401936 945834 1625322 2097333 2036678 1487253 811437 325342 94607 19470 2759 254 8
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131050 261650 517186 986398 1732750 2661628 3431588 3612532 3030398 1987440 1000374 377874 105064 20914 2888 258 8
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 11 225 3057 23987 120023 401936 945834 1625322 2097333 2036678 1487253 811437 325342 94607 19470 2759 254 8
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99983 1.99828 1.98832 1.95362 1.87832 1.76804 1.64464 1.52636 1.41943 1.32792 1.25167 1.18887 1.13902 1.09953 1.06904 1.04467 1.0155 1

tree_stats_height=34
tree_stats_numInternals=9995535
tree_stats_numLeaves=9995536
tree_stats_numNodes=19991071
tree_stats_numKeys=9995536

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

tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7338800 14652400 28962416 55238288 97034000 149051168 192168928 202301792 169702288 111296640 56020944 21160944 5883584 1171184 161728 14448 448
tree_stats_sizeInBytes=1119499976

4NodeIxPvE_object_size=56
4NodeIxPvE_allocated_count=0
4NodeIxPvE_allocated_size=0MB
4NodeIxPvE_get_from_pool=0
4NodeIxPvE_deallocated=0
4NodeIxPvE_limbo_count=0
4NodeIxPvE_limbo_details=0 0 0 

global_epoch_counter=6189

9SCXRecordIxPvE_object_size=128
9SCXRecordIxPvE_allocated_count=0
9SCXRecordIxPvE_allocated_size=0MB
9SCXRecordIxPvE_get_from_pool=0
9SCXRecordIxPvE_deallocated=0
9SCXRecordIxPvE_limbo_count=0
9SCXRecordIxPvE_limbo_details=0 0 0 

global_epoch_counter=0


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:207 2:159 4:12 8:20 16:47 32:131 64:540 128:149210 256:239422 512:94646 1024:252 2048:126
    [2^00, 2^01]: 207
    (2^01, 2^02]: 159
    (2^02, 2^03]: 12
    (2^03, 2^04]: 20
    (2^04, 2^05]: 47
    (2^05, 2^06]: 131
    (2^06, 2^07]: 540
    (2^07, 2^08]: 149210
    (2^08, 2^09]: 239422
    (2^09, 2^10]: 94646
    (2^10, 2^11]: 252
    (2^11, 2^12]: 126
sum_limbo_reclamation_event_size_total=176719980
count_limbo_reclamation_event_size_by_thread=7580 7575 7575 7576 7574 7565 7573 7576 7576 7578 7569 7577 7580 7575 7579 7576 7573 7573 7580 7576 7578 7578 7572 7577 7576 7574 7575 7573 7578 7579 7577 7576 7570 7569 7574 7576 7574 7573 7577 7574 7575 7572 7574 7573 7572 7574 7575 7575 7572 7571 7574 7570 7576 7574 7569 7572 7573 7574 7574 7572 7584 7577 7570 7574
count_limbo_reclamation_event_size_total=484772
sum_limbo_reclamation_event_count_total=485210
first_thread_announced_epoch_by_thread=12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378 12378
sum_num_inserts_by_thread=921574 920121 919793 917900 922651 922169 917933 919500 920806 922876 916451 918243 919734 922851 919201 918914 917385 913599 914947 912103 912013 910746 910809 911156 895791 915208 908662 913052 916204 914489 914432 914227 919211 920022 917967 917582 915603 917776 914444 915008 913850 922154 919028 919383 921766 921885 919957 917915 914515 915864 916705 911838 917229 917747 912757 912415 918136 915666 913344 916059 916968 915640 915534 915917
sum_num_inserts_total=58655425
sum_num_deletes_by_thread=918618 921471 919839 918020 924764 921498 917426 918497 921032 921023 916206 918033 919833 923299 919334 915694 916236 914914 913831 911910 912410 911648 911293 911097 896909 915508 910505 913392 916729 916785 915534 916640 920705 919034 916725 919855 915585 919327 917541 915265 912076 922032 920931 918489 923441 922476 917649 919023 913862 917497 917810 912825 919793 917370 914318 910673 918043 917744 913994 916268 917180 914353 917133 914963
sum_num_deletes_total=58673908
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1840192 1841592 1839632 1835920 1847415 1843667 1835359 1837997 1841838 1843899 1832657 1836276 1839567 1846150 1838535 1834608 1833621 1828513 1828778 1824013 1824423 1822394 1822102 1822253 1792700 1830716 1819167 1826444 1832933 1831274 1829966 1830867 1839916 1839056 1834692 1837437 1831188 1837103 1831985 1830273 1825926 1844186 1839959 1837872 1845207 1844361 1837606 1836938 1828377 1833361 1834515 1824663 1837022 1835117 1827075 1823088 1836179 1833410 1827338 1832327 1834148 1829993 1832667 1830880
average_num_operations_total=1833270
stdev_num_operations_total=1053
sum_num_operations_total=117329333
min_num_operations_total=1792700
max_num_operations_total=1847415
first_time_thread_terminate_by_thread=10000111 10000067 10000070 10000070 10000093 10000070 10000070 10000070 10000069 10000069 10000107 10000069 10000068 10000067 10000071 10000067 10000067 10000067 10000067 10000068 10000067 10000079 10000067 10000067 10000069 10000098 10000069 10000069 10000137 10000069 10000069 10000069 10000070 10000070 10000069 10000069 10000068 10000069 10000068 10000081 10000068 10000068 10000068 10000068 10000112 10000070 10000070 10000070 10000071 10000068 10000068 10000130 10000069 10000069 10000069 10000069 10000068 10000068 10000068 10000068 10000081 10000070 10000070 10000070
min_time_thread_terminate_total=10000067
max_time_thread_terminate_total=10000137
first_time_thread_start_by_thread=8 3 3 3 5 5 5 5 7 7 7 7 7 7 7 7 6 6 6 6 4 4 4 4 4 4 4 4 5 5 5 5 6 6 6 6 9 9 9 9 6 6 6 6 10 10 10 10 9 9 9 9 8 8 8 8 5 5 5 5 8 7 7 8
min_time_thread_start_total=3
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=99940035972858
threads_final_size=9995536
final_keysum=99940035972858
final_size=9995536
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=58655425
total_deletes=58673908
total_updates=117329333
total_queries=0
total_ops=117329333
find_throughput=0
rq_throughput=0
update_throughput=11732933
query_throughput=0
total_throughput=11732933


total find                    : 0
total rq                      : 0
total inserts                 : 58655425
total deletes                 : 58673908
total updates                 : 117329333
total queries                 : 0
total ops                     : 117329333
find throughput               : 0
rq throughput                 : 0
update throughput             : 11732933
query throughput              : 0
total throughput              : 11732933

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=0
total_execution_walltime=15.443s
REALTIME_MAIN_END_PERF_FORMAT=215971.670504785
[time_cmd_output] time_elapsed_sec=15.83, faults_major=0, faults_minor=1071542, mem_maxresident_kb=4219024, user_cputime=938.42, sys_cputime=16.14, percent_cpu=6029%
timeout=false
algorithm=brown_ext_chromatic_lf.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2