__step=000417
__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=data000417.txt
__path_data=data/data000417.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=211878.685530145
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.022s
prefilling_size=5519848 prefilling_elapsed_millis=1s
prefilling_size=5825517 prefilling_elapsed_millis=1.101s
prefilling_size=6105864 prefilling_elapsed_millis=1.201s
prefilling_size=6364088 prefilling_elapsed_millis=1.301s
prefilling_size=6603435 prefilling_elapsed_millis=1.401s
prefilling_size=6827057 prefilling_elapsed_millis=1.501s
prefilling_size=7035018 prefilling_elapsed_millis=1.602s
prefilling_size=7227346 prefilling_elapsed_millis=1.702s
prefilling_size=7405340 prefilling_elapsed_millis=1.802s
prefilling_size=7571482 prefilling_elapsed_millis=1.902s
prefilling_size=7724484 prefilling_elapsed_millis=2.003s
prefilling_size=7868737 prefilling_elapsed_millis=2.103s
prefilling_size=8000680 prefilling_elapsed_millis=2.203s
prefilling_size=8124356 prefilling_elapsed_millis=2.303s
prefilling_size=8239195 prefilling_elapsed_millis=2.404s
prefilling_size=8348168 prefilling_elapsed_millis=2.504s
prefilling_size=8448810 prefilling_elapsed_millis=2.604s
prefilling_size=8544159 prefilling_elapsed_millis=2.704s
prefilling_size=8632395 prefilling_elapsed_millis=2.804s
prefilling_size=8715509 prefilling_elapsed_millis=2.905s
prefilling_size=8791843 prefilling_elapsed_millis=3.005s
prefilling_size=8864571 prefilling_elapsed_millis=3.106s
prefilling_size=8932292 prefilling_elapsed_millis=3.206s
prefilling_size=8996464 prefilling_elapsed_millis=3.306s
prefilling_size=9056294 prefilling_elapsed_millis=3.406s
prefilling_size=9113031 prefilling_elapsed_millis=3.507s
prefilling_size=9167166 prefilling_elapsed_millis=3.607s
prefilling_size=9214730 prefilling_elapsed_millis=3.707s
prefilling_size=9261439 prefilling_elapsed_millis=3.807s
prefilling_size=9304229 prefilling_elapsed_millis=3.907s
prefilling_size=9345309 prefilling_elapsed_millis=4.008s
prefilling_size=9384339 prefilling_elapsed_millis=4.108s
prefilling_size=9420438 prefilling_elapsed_millis=4.208s
prefilling_size=9455451 prefilling_elapsed_millis=4.308s
prefilling_size=9486620 prefilling_elapsed_millis=4.409s
prefilling_size=9515359 prefilling_elapsed_millis=4.509s
prefilling_size=9543561 prefilling_elapsed_millis=4.609s
prefilling_size=9569437 prefilling_elapsed_millis=4.709s
prefilling_size=9594985 prefilling_elapsed_millis=4.809s
prefilling_size=9617783 prefilling_elapsed_millis=4.91s
prefilling_size=9640288 prefilling_elapsed_millis=5.01s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 359685 keys with 64 threads...
tid=0 thread_prefill_amount=162500 percent_done_estimate=2891.4 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0569932s
prefilling_size=10000000 prefilling_elapsed_millis=0.059s
finished prefilling to size 10000000 for expected size 10000000 keysum=100003519325532, performing 67601229 updates; total_prefilling_elapsed_ms=60 ms)
pref_size=10000000
pref_millis=60
prefill_elapsed_ms=60
4NodeIxPvE_object_size=56
4NodeIxPvE_allocated_count=0
4NodeIxPvE_allocated_size=0MB
4NodeIxPvE_get_from_pool=0
4NodeIxPvE_deallocated=0
4NodeIxPvE_limbo_count=47108
4NodeIxPvE_limbo_details=17714 16467 12927 

global_epoch_counter=2369

9SCXRecordIxPvE_object_size=128
9SCXRecordIxPvE_allocated_count=0
9SCXRecordIxPvE_allocated_size=0MB
9SCXRecordIxPvE_get_from_pool=0
9SCXRecordIxPvE_deallocated=0
9SCXRecordIxPvE_limbo_count=22615
9SCXRecordIxPvE_limbo_details=8487 7925 6203 

global_epoch_counter=0

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=211883.910868911
REALTIME_END_PERF_FORMAT=211893.910941710

###############################################################################
################################## 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 65522 130710 257415 485059 837295 1262689 1614968 1722502 1510539 1072737 605149 263487 86127 20217 3157 317 23 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 14 334 4005 29771 132823 411901 910410 1507434 1934465 1948341 1540325 946811 440847 152037 37277 5997 611 45 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131044 261420 514830 970118 1674590 2525378 3229936 3445004 3021078 2145474 1210298 526974 172254 40434 6314 634 46 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 14 334 4005 29771 132823 411901 910410 1507434 1934465 1948341 1540325 946811 440847 152037 37277 5997 611 45 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99979 1.99745 1.98468 1.94217 1.86309 1.75403 1.6395 1.53329 1.43847 1.35508 1.28206 1.2177 1.16344 1.11737 1.07808 1.05021 1.03628 1.02174 1

tree_stats_height=35
tree_stats_numInternals=10003449
tree_stats_numLeaves=10003450
tree_stats_numNodes=20006899
tree_stats_numKeys=10003450

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

tree_stats_bytesAtDepth=56 112 224 448 896 1792 3584 7168 14336 28672 57344 114688 229376 458752 917504 1835008 3670016 7338464 14639520 28830480 54326608 93777040 141421168 180876416 192920224 169180368 120146544 67776688 29510544 9646224 2264304 353584 35504 2576 112
tree_stats_sizeInBytes=1120386344

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

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:34 2:26 4:24 8:2454 16:176234 32:316039 64:144535 128:473 256:133
    [2^00, 2^01]: 34
    (2^01, 2^02]: 26
    (2^02, 2^03]: 24
    (2^03, 2^04]: 2454
    (2^04, 2^05]: 176234
    (2^05, 2^06]: 316039
    (2^06, 2^07]: 144535
    (2^07, 2^08]: 473
    (2^08, 2^09]: 133
sum_limbo_reclamation_event_size_total=29958185
count_limbo_reclamation_event_size_by_thread=9998 10000 9999 9998 10000 10000 10000 10000 9998 9998 10000 9998 10000 10000 9998 10000 10000 10000 10000 9998 9998 10000 10000 10000 9998 9998 9998 10000 9998 10000 10000 10000 10000 10000 10000 9998 10000 10000 10000 10000 10000 10000 9997 9998 9999 10000 9998 10000 9998 9998 10000 10000 9999 10000 10000 10000 10000 9999 9999 10000 9998 9998 9998 10000
count_limbo_reclamation_event_size_total=639952
sum_limbo_reclamation_event_count_total=753016
first_thread_announced_epoch_by_thread=16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502 16502
sum_num_inserts_by_thread=183083 182899 182179 182267 184317 182468 182496 182116 182932 182196 181997 181094 180524 180467 180173 180421 181241 181479 183052 182167 182680 182389 181928 181314 181819 180667 180351 179926 181151 180797 179809 179772 183385 183870 182210 183352 182182 182566 182667 183302 181706 180882 181032 181100 181132 181178 182069 182111 181859 182047 181092 181196 182420 181754 180973 180118 180727 179284 180101 180559 182945 182131 182276 182110
sum_num_inserts_total=11628507
sum_num_deletes_by_thread=182973 183280 182486 182235 182841 182643 182920 182551 182149 182357 182071 181513 181004 181554 179762 179846 181067 180095 182189 182172 182248 182077 181466 182453 182673 181710 180874 180666 179729 181076 179882 179084 184171 182418 182801 182330 183091 183144 183055 181696 182106 181894 180201 180782 181598 180245 183015 182391 182529 182253 181092 181913 182186 181408 180738 180064 179638 179126 179516 178837 182246 182463 181895 182143
sum_num_deletes_total=11624631
sum_num_searches_by_thread=3300830 3296558 3279681 3283232 3296081 3293695 3287184 3283738 3283547 3278295 3268092 3262489 3262657 3254636 3250126 3245916 3258340 3256251 3284223 3279079 3282066 3283256 3273273 3272089 3276618 3264578 3247348 3242368 3248953 3247128 3237986 3236340 3304594 3297501 3286838 3286267 3292766 3291804 3282626 3273472 3267614 3261498 3252657 3250020 3254896 3252408 3287816 3286518 3276953 3277335 3262747 3268287 3276950 3265109 3249327 3242748 3248767 3244844 3235227 3235845 3289201 3287030 3279568 3279068
sum_num_searches_total=209264954
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3666886 3662737 3644346 3647734 3663239 3658806 3652600 3648405 3648628 3642848 3632160 3625096 3624185 3616657 3610061 3606183 3620648 3617825 3649464 3643418 3646994 3647722 3636667 3635856 3641110 3626955 3608573 3602960 3609833 3609001 3597677 3595196 3672150 3663789 3651849 3651949 3658039 3657514 3648348 3638470 3631426 3624274 3613890 3611902 3617626 3613831 3652900 3651020 3641341 3641635 3624931 3631396 3641556 3628271 3611038 3602930 3609132 3603254 3594844 3595241 3654392 3651624 3643739 3643321
average_num_operations_total=3633095
stdev_num_operations_total=2613
sum_num_operations_total=232518092
min_num_operations_total=3594844
max_num_operations_total=3672150
first_time_thread_terminate_by_thread=10000113 10000073 10000072 10000072 10000073 10000073 10000073 10000073 10000075 10000075 10000075 10000075 10000075 10000075 10000076 10000076 10000074 10000074 10000074 10000075 10000074 10000074 10000074 10000074 10000075 10000075 10000075 10000075 10000072 10000072 10000073 10000072 10000073 10000073 10000073 10000073 10000076 10000076 10000076 10000076 10000075 10000075 10000075 10000075 10000073 10000073 10000073 10000073 10000076 10000076 10000076 10000076 10000076 10000076 10000076 10000076 10000073 10000073 10000073 10000073 10000074 10000074 10000074 10000074
min_time_thread_terminate_total=10000072
max_time_thread_terminate_total=10000113
first_time_thread_start_by_thread=12 5 4 3 4 5 5 5 5 5 5 5 7 7 7 7 5 5 5 5 5 6 6 5 7 7 7 7 7 7 7 7 8 8 8 8 6 6 6 6 9 9 9 9 6 6 6 6 9 9 9 9 9 9 9 9 10 10 10 10 6 6 6 6
min_time_thread_start_total=3
max_time_thread_start_total=12
sum_duration_all_ops_total=

threads_final_keysum=100029748410648
threads_final_size=10003450
final_keysum=100029748410648
final_size=10003450
validate_result=success
Validation OK.
Structural validation OK.

total_find=209264954
total_rq=0
total_inserts=11628507
total_deletes=11624631
total_updates=23253138
total_queries=209264954
total_ops=232518092
find_throughput=20926495
rq_throughput=0
update_throughput=2325313
query_throughput=20926495
total_throughput=23251809


total find                    : 209264954
total rq                      : 0
total inserts                 : 11628507
total deletes                 : 11624631
total updates                 : 23253138
total queries                 : 209264954
total ops                     : 232518092
find throughput               : 20926495
rq throughput                 : 0
update throughput             : 2325313
query throughput              : 20926495
total throughput              : 23251809

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=964385444593338
total_execution_walltime=15.457s
REALTIME_MAIN_END_PERF_FORMAT=211894.143654987
[time_cmd_output] time_elapsed_sec=15.71, faults_major=0, faults_minor=624330, mem_maxresident_kb=2496380, user_cputime=960.45, sys_cputime=7.55, percent_cpu=6159%
timeout=false
algorithm=brown_ext_chromatic_lf.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1