__step=000753
__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=data000753.txt
__path_data=data/data000753.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=215940.320674388
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.018s
prefilling_size=5519906 prefilling_elapsed_millis=1s
prefilling_size=5821979 prefilling_elapsed_millis=1.1s
prefilling_size=6096861 prefilling_elapsed_millis=1.201s
prefilling_size=6351538 prefilling_elapsed_millis=1.301s
prefilling_size=6588011 prefilling_elapsed_millis=1.401s
prefilling_size=6806387 prefilling_elapsed_millis=1.501s
prefilling_size=7007968 prefilling_elapsed_millis=1.601s
prefilling_size=7196131 prefilling_elapsed_millis=1.701s
prefilling_size=7370843 prefilling_elapsed_millis=1.801s
prefilling_size=7531932 prefilling_elapsed_millis=1.901s
prefilling_size=7680278 prefilling_elapsed_millis=2.002s
prefilling_size=7820610 prefilling_elapsed_millis=2.102s
prefilling_size=7952223 prefilling_elapsed_millis=2.202s
prefilling_size=8073169 prefilling_elapsed_millis=2.302s
prefilling_size=8188304 prefilling_elapsed_millis=2.402s
prefilling_size=8293891 prefilling_elapsed_millis=2.502s
prefilling_size=8390428 prefilling_elapsed_millis=2.602s
prefilling_size=8477893 prefilling_elapsed_millis=2.702s
prefilling_size=8566010 prefilling_elapsed_millis=2.802s
prefilling_size=8648423 prefilling_elapsed_millis=2.903s
prefilling_size=8726269 prefilling_elapsed_millis=3.003s
prefilling_size=8797860 prefilling_elapsed_millis=3.103s
prefilling_size=8864410 prefilling_elapsed_millis=3.203s
prefilling_size=8929571 prefilling_elapsed_millis=3.303s
prefilling_size=8988421 prefilling_elapsed_millis=3.404s
prefilling_size=9044348 prefilling_elapsed_millis=3.504s
prefilling_size=9096935 prefilling_elapsed_millis=3.604s
prefilling_size=9145625 prefilling_elapsed_millis=3.704s
prefilling_size=9192110 prefilling_elapsed_millis=3.804s
prefilling_size=9235551 prefilling_elapsed_millis=3.904s
prefilling_size=9276621 prefilling_elapsed_millis=4.004s
prefilling_size=9317627 prefilling_elapsed_millis=4.104s
prefilling_size=9352073 prefilling_elapsed_millis=4.204s
prefilling_size=9386495 prefilling_elapsed_millis=4.305s
prefilling_size=9418844 prefilling_elapsed_millis=4.405s
prefilling_size=9447961 prefilling_elapsed_millis=4.505s
prefilling_size=9477244 prefilling_elapsed_millis=4.605s
prefilling_size=9504654 prefilling_elapsed_millis=4.705s
prefilling_size=9530056 prefilling_elapsed_millis=4.805s
prefilling_size=9554967 prefilling_elapsed_millis=4.905s
prefilling_size=9577177 prefilling_elapsed_millis=5.005s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 422814 keys with 64 threads...
tid=0 thread_prefill_amount=128125 percent_done_estimate=1939.4 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=131250 percent_done_estimate=1986.7 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=134375 percent_done_estimate=2034.0 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0626432s
prefilling_size=10000000 prefilling_elapsed_millis=0.065s
finished prefilling to size 10000000 for expected size 10000000 keysum=99997185262833, performing 69025125 updates; total_prefilling_elapsed_ms=65 ms)
pref_size=10000000
pref_millis=65
prefill_elapsed_ms=66
4NodeIxPvE_object_size=56
4NodeIxPvE_allocated_count=0
4NodeIxPvE_allocated_size=0MB
4NodeIxPvE_get_from_pool=0
4NodeIxPvE_deallocated=0
4NodeIxPvE_limbo_count=47463
4NodeIxPvE_limbo_details=15363 17632 14468 

global_epoch_counter=2379

9SCXRecordIxPvE_object_size=128
9SCXRecordIxPvE_allocated_count=0
9SCXRecordIxPvE_allocated_size=0MB
9SCXRecordIxPvE_get_from_pool=0
9SCXRecordIxPvE_deallocated=0
9SCXRecordIxPvE_limbo_count=22705
9SCXRecordIxPvE_limbo_details=7394 8499 6812 

global_epoch_counter=0

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=215945.538880021
REALTIME_END_PERF_FORMAT=215955.538945963

###############################################################################
################################## 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.062s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65521 130728 257809 489416 856465 1313354 1698819 1798010 1523621 1013842 517978 199266 56623 11874 1742 174 9 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 15 314 3647 26202 122367 399576 927889 1599628 2072399 2033400 1509706 836690 341909 101372 22006 3310 339 18
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131042 261456 515618 978832 1712930 2626708 3397638 3596020 3047242 2027684 1035956 398532 113246 23748 3484 348 18
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 15 314 3647 26202 122367 399576 927889 1599628 2072399 2033400 1509706 836690 341909 101372 22006 3310 339 18
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99977 1.9976 1.98605 1.94918 1.87499 1.76673 1.64675 1.52919 1.4237 1.33271 1.25545 1.19235 1.14208 1.10485 1.07335 1.04994 1.02586 1

tree_stats_height=34
tree_stats_numInternals=10000786
tree_stats_numLeaves=10000787
tree_stats_numNodes=20001573
tree_stats_numKeys=10000787

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

tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7338352 14641536 28874608 54814592 95924080 147095648 190267728 201377120 170645552 113550304 58013536 22317792 6341776 1329888 195104 19488 1008
tree_stats_sizeInBytes=1120088088

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

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:150 2:78 4:5 8:13 16:39 32:138 64:577 128:150060 256:240262 512:94805 1024:209 2048:63
    [2^00, 2^01]: 150
    (2^01, 2^02]: 78
    (2^02, 2^03]: 5
    (2^03, 2^04]: 13
    (2^04, 2^05]: 39
    (2^05, 2^06]: 138
    (2^06, 2^07]: 577
    (2^07, 2^08]: 150060
    (2^08, 2^09]: 240262
    (2^09, 2^10]: 94805
    (2^10, 2^11]: 209
    (2^11, 2^12]: 63
sum_limbo_reclamation_event_size_total=177275465
count_limbo_reclamation_event_size_by_thread=7603 7602 7602 7606 7601 7598 7601 7602 7598 7598 7606 7599 7592 7600 7597 7602 7597 7602 7598 7601 7601 7599 7598 7599 7600 7599 7599 7600 7600 7602 7599 7597 7602 7604 7602 7601 7604 7601 7597 7596 7606 7605 7601 7603 7594 7599 7595 7596 7600 7599 7602 7603 7603 7602 7593 7601 7602 7599 7600 7594 7597 7599 7601 7600
count_limbo_reclamation_event_size_total=486399
sum_limbo_reclamation_event_count_total=486676
first_thread_announced_epoch_by_thread=12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362 12362
sum_num_inserts_by_thread=922772 922099 919244 921473 925511 923650 917772 917784 922008 921611 921041 918736 898650 922453 920576 921196 920415 921086 915414 916517 918035 916569 912761 914558 917133 913628 915943 918467 921268 919323 919159 915009 925196 923129 920212 915807 921756 921841 918270 918601 925316 925695 922833 923375 924183 924307 920017 920303 920760 920963 916220 916364 922477 920938 914784 919190 919359 921707 920750 919104 921191 922250 919849 922074
sum_num_inserts_total=58860682
sum_num_deletes_by_thread=923567 922990 921027 922205 923799 925860 919483 917364 921051 921989 920194 918291 899287 922774 920759 919979 919741 919654 914998 916140 918755 916891 912600 911436 918024 914459 915303 917596 921308 919566 919263 913738 925062 923384 918583 918881 920598 922457 918848 916650 922127 925711 923950 922513 924400 923474 920540 917974 920946 917841 916599 918800 921709 918475 916100 917604 921667 921783 919630 919706 921198 922353 921203 921389
sum_num_deletes_total=58852246
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1846339 1845089 1840271 1843678 1849310 1849510 1837255 1835148 1843059 1843600 1841235 1837027 1797937 1845227 1841335 1841175 1840156 1840740 1830412 1832657 1836790 1833460 1825361 1825994 1835157 1828087 1831246 1836063 1842576 1838889 1838422 1828747 1850258 1846513 1838795 1834688 1842354 1844298 1837118 1835251 1847443 1851406 1846783 1845888 1848583 1847781 1840557 1838277 1841706 1838804 1832819 1835164 1844186 1839413 1830884 1836794 1841026 1843490 1840380 1838810 1842389 1844603 1841052 1843463
average_num_operations_total=1839264
stdev_num_operations_total=1000
sum_num_operations_total=117712928
min_num_operations_total=1797937
max_num_operations_total=1851406
first_time_thread_terminate_by_thread=10000109 10000066 10000066 10000069 10000068 10000068 10000068 10000068 10000067 10000066 10000066 10000081 10000069 10000069 10000069 10000069 10000066 10000067 10000066 10000067 10000104 10000067 10000067 10000105 10000068 10000077 10000067 10000076 10000066 10000066 10000066 10000068 10000065 10000066 10000065 10000068 10000068 10000068 10000068 10000068 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000069 10000067 10000067 10000066 10000067 10000069 10000069 10000088 10000077 10000067 10000067 10000108 10000067 10000068 10000068 10000068 10000068
min_time_thread_terminate_total=10000065
max_time_thread_terminate_total=10000109
first_time_thread_start_by_thread=10 3 3 3 6 6 6 6 8 8 8 8 6 6 6 6 7 7 7 7 7 7 7 7 8 8 8 8 9 9 9 9 5 5 5 5 6 6 6 6 5 5 5 5 4 4 4 4 5 5 5 5 9 9 9 9 8 8 8 8 5 5 5 5
min_time_thread_start_total=3
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=100004739737849
threads_final_size=10000787
final_keysum=100004739737849
final_size=10000787
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=58860682
total_deletes=58852246
total_updates=117712928
total_queries=0
total_ops=117712928
find_throughput=0
rq_throughput=0
update_throughput=11771292
query_throughput=0
total_throughput=11771292


total find                    : 0
total rq                      : 0
total inserts                 : 58860682
total deletes                 : 58852246
total updates                 : 117712928
total queries                 : 0
total ops                     : 117712928
find throughput               : 0
rq throughput                 : 0
update throughput             : 11771292
query throughput              : 0
total throughput              : 11771292

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=0
total_execution_walltime=15.449s
REALTIME_MAIN_END_PERF_FORMAT=215955.770696200
[time_cmd_output] time_elapsed_sec=15.82, faults_major=0, faults_minor=1071525, mem_maxresident_kb=4264632, user_cputime=939.09, sys_cputime=16.51, percent_cpu=6037%
timeout=false
algorithm=brown_ext_chromatic_lf.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1