__step=000418
__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 5.0 5.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000418.txt
__path_data=data/data000418.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=211894.466701310
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=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=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.027s
prefilling_size=5587190 prefilling_elapsed_millis=1s
prefilling_size=5868888 prefilling_elapsed_millis=1.101s
prefilling_size=6144783 prefilling_elapsed_millis=1.201s
prefilling_size=6400192 prefilling_elapsed_millis=1.301s
prefilling_size=6634907 prefilling_elapsed_millis=1.401s
prefilling_size=6852901 prefilling_elapsed_millis=1.501s
prefilling_size=7056475 prefilling_elapsed_millis=1.602s
prefilling_size=7242859 prefilling_elapsed_millis=1.702s
prefilling_size=7417393 prefilling_elapsed_millis=1.802s
prefilling_size=7578084 prefilling_elapsed_millis=1.902s
prefilling_size=7729264 prefilling_elapsed_millis=2.003s
prefilling_size=7870904 prefilling_elapsed_millis=2.103s
prefilling_size=8003444 prefilling_elapsed_millis=2.203s
prefilling_size=8127563 prefilling_elapsed_millis=2.303s
prefilling_size=8242532 prefilling_elapsed_millis=2.404s
prefilling_size=8349791 prefilling_elapsed_millis=2.504s
prefilling_size=8450085 prefilling_elapsed_millis=2.604s
prefilling_size=8543981 prefilling_elapsed_millis=2.704s
prefilling_size=8631239 prefilling_elapsed_millis=2.804s
prefilling_size=8713295 prefilling_elapsed_millis=2.905s
prefilling_size=8792191 prefilling_elapsed_millis=3.005s
prefilling_size=8865074 prefilling_elapsed_millis=3.106s
prefilling_size=8933676 prefilling_elapsed_millis=3.206s
prefilling_size=8998080 prefilling_elapsed_millis=3.306s
prefilling_size=9058793 prefilling_elapsed_millis=3.406s
prefilling_size=9114854 prefilling_elapsed_millis=3.506s
prefilling_size=9166008 prefilling_elapsed_millis=3.607s
prefilling_size=9214874 prefilling_elapsed_millis=3.707s
prefilling_size=9261038 prefilling_elapsed_millis=3.807s
prefilling_size=9305392 prefilling_elapsed_millis=3.907s
prefilling_size=9347275 prefilling_elapsed_millis=4.008s
prefilling_size=9386053 prefilling_elapsed_millis=4.108s
prefilling_size=9421295 prefilling_elapsed_millis=4.208s
prefilling_size=9457050 prefilling_elapsed_millis=4.308s
prefilling_size=9488138 prefilling_elapsed_millis=4.408s
prefilling_size=9517973 prefilling_elapsed_millis=4.509s
prefilling_size=9547409 prefilling_elapsed_millis=4.609s
prefilling_size=9572895 prefilling_elapsed_millis=4.709s
prefilling_size=9597424 prefilling_elapsed_millis=4.809s
prefilling_size=9620654 prefilling_elapsed_millis=4.91s
prefilling_size=9642206 prefilling_elapsed_millis=5.01s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 357740 keys with 64 threads...
tid=0 thread_prefill_amount=143750 percent_done_estimate=2571.7 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=146875 percent_done_estimate=2627.6 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0557504s
prefilling_size=10000000 prefilling_elapsed_millis=0.058s
finished prefilling to size 10000000 for expected size 10000000 keysum=99995852085647, performing 67634310 updates; total_prefilling_elapsed_ms=58 ms)
pref_size=10000000
pref_millis=58
prefill_elapsed_ms=58
4NodeIxPvE_object_size=56
4NodeIxPvE_allocated_count=0
4NodeIxPvE_allocated_size=0MB
4NodeIxPvE_get_from_pool=0
4NodeIxPvE_deallocated=0
4NodeIxPvE_limbo_count=47306
4NodeIxPvE_limbo_details=15917 16868 14521 

global_epoch_counter=2361

9SCXRecordIxPvE_object_size=128
9SCXRecordIxPvE_allocated_count=0
9SCXRecordIxPvE_allocated_size=0MB
9SCXRecordIxPvE_get_from_pool=0
9SCXRecordIxPvE_deallocated=0
9SCXRecordIxPvE_limbo_count=22856
9SCXRecordIxPvE_limbo_details=7732 8135 6989 

global_epoch_counter=0

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=211899.690876852
REALTIME_END_PERF_FORMAT=211909.690947327

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

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65505 130574 256583 481971 828575 1247936 1599505 1712803 1512710 1084906 620478 275311 91723 21867 3443 342 25 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 31 436 4565 31195 135367 409214 896367 1486207 1912896 1940514 1549334 965645 458899 161579 40291 6544 659 48 4
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131010 261148 513166 963942 1657150 2495872 3199010 3425606 3025420 2169812 1240956 550622 183446 43734 6886 684 50 4
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 31 436 4565 31195 135367 409214 896367 1486207 1912896 1940514 1549334 965645 458899 161579 40291 6544 659 48 4
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99953 1.99667 1.98252 1.93921 1.85957 1.75306 1.64086 1.53542 1.44159 1.3586 1.28596 1.22185 1.16658 1.1192 1.07873 1.04967 1.03655 1.04 1

tree_stats_height=35
tree_stats_numInternals=9999794
tree_stats_numLeaves=9999795
tree_stats_numNodes=19999589
tree_stats_numKeys=9999795

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

tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7336560 14624288 28737296 53980752 92800400 139768832 179144560 191833936 169423520 121509472 69493536 30834832 10272976 2449104 385616 38304 2800 224
tree_stats_sizeInBytes=1119976984

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

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:36 2:28 4:17 8:2414 16:175638 32:315505 64:145669 128:536 256:141
    [2^00, 2^01]: 36
    (2^01, 2^02]: 28
    (2^02, 2^03]: 17
    (2^03, 2^04]: 2414
    (2^04, 2^05]: 175638
    (2^05, 2^06]: 315505
    (2^06, 2^07]: 145669
    (2^07, 2^08]: 536
    (2^08, 2^09]: 141
sum_limbo_reclamation_event_size_total=29998260
count_limbo_reclamation_event_size_by_thread=10000 10000 10000 9998 10000 9999 10000 9999 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 9998 9999 10000 9999 10000 10000 10000 9999 10000 10000 9999 10000 9999 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 9999 10000 10000 10000 10000 10000 9999 10000 10000 10000 9999 9999 9999 10000 10000
count_limbo_reclamation_event_size_total=639984
sum_limbo_reclamation_event_count_total=752594
first_thread_announced_epoch_by_thread=16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480 16480
sum_num_inserts_by_thread=182925 183047 182045 181402 182078 183302 181662 183121 181810 181711 181960 181639 181163 181365 180619 179410 181820 181109 180406 180900 182452 182562 181325 182045 182819 182106 181424 180994 180756 180974 179739 180096 182412 181195 183242 182817 183498 183177 182779 182147 182267 182553 182277 181468 180668 180808 180455 180164 182551 182402 181186 181594 181450 182772 181326 180098 181354 180454 179489 179885 180480 180772 182405 182764
sum_num_inserts_total=11623695
sum_num_deletes_by_thread=182927 182751 182512 180957 182836 183235 181779 182117 182182 182435 181480 181609 181101 181863 180658 179930 180698 180509 180336 180468 182252 182967 182425 181680 181865 182516 181749 181459 180093 180766 179551 179695 181398 181252 182663 183785 183520 182943 182417 182885 182875 182384 181872 180928 181043 181838 180709 179794 182132 182270 182086 181919 181982 181508 181896 181169 180572 179701 180485 179941 180056 180992 182587 181919
sum_num_deletes_total=11622922
sum_num_searches_by_thread=3296283 3296133 3277079 3263261 3274279 3291883 3284681 3277278 3282233 3279485 3265554 3268384 3266413 3254805 3241448 3241436 3258021 3258865 3245701 3246028 3290430 3287668 3274024 3276562 3274043 3273251 3265898 3253951 3254562 3248772 3239997 3237782 3267478 3265022 3298307 3293482 3294927 3299677 3287659 3288507 3289375 3281508 3264343 3260729 3265601 3259772 3250872 3250356 3284789 3278630 3266613 3266688 3275120 3273021 3265227 3253456 3255375 3250310 3242860 3238198 3247737 3251183 3286468 3284709
sum_num_searches_total=209184189
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3662135 3661931 3641636 3625620 3639193 3658420 3648122 3642516 3646225 3643631 3628994 3631632 3628677 3618033 3602725 3600776 3620539 3620483 3606443 3607396 3655134 3653197 3637774 3640287 3638727 3637873 3629071 3616404 3615411 3610512 3599287 3597573 3631288 3627469 3664212 3660084 3661945 3665797 3652855 3653539 3654517 3646445 3628492 3623125 3627312 3622418 3612036 3610314 3649472 3643302 3629885 3630201 3638552 3637301 3628449 3614723 3617301 3610465 3602834 3598024 3608273 3612947 3651460 3649392
average_num_operations_total=3631731
stdev_num_operations_total=2410
sum_num_operations_total=232430806
min_num_operations_total=3597573
max_num_operations_total=3665797
first_time_thread_terminate_by_thread=10000114 10000070 10000070 10000070 10000073 10000073 10000073 10000073 10000071 10000071 10000071 10000071 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000071 10000071 10000071 10000071 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000071 10000071 10000071 10000071 10000074 10000073 10000074 10000074 10000072 10000072 10000072 10000072 10000070 10000070 10000070 10000070 10000072 10000072 10000072 10000072 10000071 10000072 10000072 10000072 10000071 10000070 10000070 10000071 10000070 10000070 10000070 10000070
min_time_thread_terminate_total=10000070
max_time_thread_terminate_total=10000114
first_time_thread_start_by_thread=12 3 3 3 9 9 9 9 8 8 8 8 8 8 8 8 10 10 10 10 7 7 7 7 9 9 9 9 7 7 7 7 4 4 4 4 7 7 7 7 9 9 9 9 5 6 5 6 6 6 6 6 5 4 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=3
max_time_thread_start_total=12
sum_duration_all_ops_total=

threads_final_keysum=99988655346231
threads_final_size=9999795
final_keysum=99988655346231
final_size=9999795
validate_result=success
Validation OK.
Structural validation OK.

total_find=209184189
total_rq=0
total_inserts=11623695
total_deletes=11622922
total_updates=23246617
total_queries=209184189
total_ops=232430806
find_throughput=20918418
rq_throughput=0
update_throughput=2324661
query_throughput=20918418
total_throughput=23243080


total find                    : 209184189
total rq                      : 0
total inserts                 : 11623695
total deletes                 : 11622922
total updates                 : 23246617
total queries                 : 209184189
total ops                     : 232430806
find throughput               : 20918418
rq throughput                 : 0
update throughput             : 2324661
query throughput              : 20918418
total throughput              : 23243080

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=1029656481784667
total_execution_walltime=15.453s
REALTIME_MAIN_END_PERF_FORMAT=211909.921033382
[time_cmd_output] time_elapsed_sec=15.71, faults_major=0, faults_minor=624233, mem_maxresident_kb=2495968, user_cputime=960.20, sys_cputime=7.80, percent_cpu=6161%
timeout=false
algorithm=brown_ext_chromatic_lf.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2