lucene-solr-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Dalton Gooding <daltonwestco...@yahoo.com.au.INVALID>
Subject Re: newSearcher autowarming queries in solrconfig.xml run but does not appear to warm cache
Date Sun, 09 Oct 2016 23:08:11 GMT
Erick,
I have tried tuning the queries with some limited success. I still get drastic differences
between the first time I fire my warming query (after newSearcher ran query) and the second
time, or any variant of the query i.e. removing fields or changing parameters, it runs much
faster.
I am not sure what I am missing here, I put a query into the newSearcher section that runs
fine, but the exact same query run after warming still takes the full time of a un-warmed
query.
Can you break it down to the most basic type of newSearcher query to try and shrink the gap
between first query and subsequent queries sent?
I cannot see why sending the same query after a newSearcher is slow, when subsequent queries
run faster. I figured this was the idea of the newSearcher stanza's.  

    On Friday, 7 October 2016, 14:45, Erick Erickson <erickerickson@gmail.com> wrote:
 

 Replying on the public thread, somehow your mail was sent to me privately.

Pasted your email to me below for others.

You are still confusing documents and results. Forget about the rows
parameter, for this discussion it's irrelevant.

The QTime is the time spent searching. It is unaffected by whether a
document is in the documentCache or not.
It _solely_ measures the time that Solr/Lucene take to find the top N
documents (where N is the rows param) and
record their internal Lucene doc ID.

Increasing the rows or the document cache won't change anything about
the QTime. The documentCache is
totally the wrong place to focus.


The response when you re-submit the query suggests that getting the
top N docs' internal Lucene ID is
fetched from the queryResultCache. Changing the window size is also
irrelevant to this discussion. If you
vary the query even slightly you won't hit the queryResultCache. A
very easy way to check this is the
admin UI>>select core>>plugins/stats>>QueryHandler and then probably
the "select" handler. If you see
the hits go up after the fast query then you're getting the results
from the querResultCache.

What _is_ relevant is populating the low-level Lucene caches with
values from the indexed terms. My
contention is that this is not happening with match-all queries, i.e.
field:* or field:[* TO *] because in
those cases, a doc matches or doesn't based on whether it has anything
in the field. There's no point
in finding values since it doesn't matter anyway. And "finding values"
means reading indexed terms
from disk into low-level Lucene caches.

When I say "populate the low-level Lucene caches", what I'm really
talking about is reading them from
disk into your physical memory via MMapDirectory, see Uwe's excellent blog:
http://blog.thetaphi.de/2012/07/use-lucenes-mmapdirectory-on-64bit.html

So the suggestion is that you use real values from your index or
possibly ranges is so that part or all
of your disk files get read into MMapDirectorySpace via the first or
new Searcher event.

Please just give it a try. My bet is that you'll see your QTime values
first time after autowarming
go down. Significantly. Be sure to use a wide variety of different
values for autowarming.

BTW, the autowarmCounts in solrconfig.xml filterCache and
queryResultCache are intended
to warm by using the last N fq or q clauses on the theory that the
most recent N are predictive
of the next N.

Best,
Erick


***************

I believe the return time back to the command line from the curl
command and the QTime as shown below

time curl -v 'http://<SolR>/solr/core1/select?fq=DataType_s%3AProduct&fq=WebSections_ms%3Ahouse&fq=%28VisibleOnline_ms%3ANAT+OR+VisibleOnline_ms%3A7%29&fq=%7B%21tag%3Dcurrent_group%7DGroupIds_ms%3A458&sort=SalesRank_f+desc&facet=true&facet.field=%7B%21ex%3Dcurrent_group%7Dattr_GroupLevel0&facet.field=BrandID_s&facet.field=%7B%21ex%3Dcurrent_group%7Dattr_GroupLevel2&facet.field=%7B%21ex%3Dcurrent_group%7Dattr_GroupLevel1&facet.field=SubBrandID_s&facet.field=ProductAttr_967_ms&facet.field=ProductAttr_NEG21_ms&facet.field=ProductAttr_1852_ms&facet.query=Price_7_f%3A%5B%2A+TO+%2A%5D&facet.query=Price_2_f%3A%5B%2A+TO+%2A%5D&facet.query=Price_3_f%3A%5B%2A+TO+%2A%5D&facet.query=Price_4_f%3A%5B%2A+TO+%2A%5D&facet.query=Price_5_f%3A%5B%2A+TO+%2A%5D&facet.query=Price_6_f%3A%5B%2A+TO+%2A%5D&facet.mincount=1&wt=json&json.nl=map&q=%28title%3A%2A+OR+text%3A%2A%29+AND+%28ms%3ALive%29&start=0&rows=24'

real    0m1.436s
user    0m0.001s
sys    0m0.006s

"QTime":1387

>From what you suggested, changing the rows value from 20 to something
greater should add more documents to the cache. Injunction with tuning
the queries to remove the * wild card, this should provide a better
warming query?

Should I also increase the queryResultWindowSize in the solrconfig.xml
to help built out the cache?

Cheers,

Guy





On Thu, Oct 6, 2016 at 4:43 PM, Dalton Gooding
<daltonwestcoast@yahoo.com.au> wrote:
> Erick,
>
> Thanks for the response. After I run the initial query and get a long
> response time, if I change the query to remove or add additional query
> statements, I find the speed is good.
>
> If I run the modified query after a new searcher has registered, the
> response is slow but after the modified query has been completed, the
> warming query sent from CuRl is much faster. I assume it is because the
> document cache has updated with the documents from the modified query. A
> large number of our queries work with the same document set, I am trying to
> get a warming query to populate the document cache to be as big as feasible.
>
> Should the firstSearcher and newSearcher warm the document cache?
>
>
> On Friday, 7 October 2016, 9:31, Erick Erickson <erickerickson@gmail.com>
> wrote:
>
>
> Submitting the exact same query twice will return results from the
> queryResultCache. I'm not entirely
> sure that the firstSearcher events get put into the cache.
>
> So if you change the query even slighty my guess is that you'll see
> response times very close to your
> original ones of over a second.
>
> Best,
> Erick
>
> On Thu, Oct 6, 2016 at 2:56 PM, Dalton Gooding
> <daltonwestcoast@yahoo.com.au.invalid> wrote:
>> After setting a number of newSearcher and firstSearcher queries, I can see
>> in the console logs that the queries are run, but when I run the same query
>> against the new searcher (using CuRL), I get a slow response time for the
>> first run.
>>
>> Config:
>>    <listener event="newSearcher" class="solr.QuerySenderListener">
>> <arr name="queries">        <lst> <str name="fq">DataType_s:Product</str>
>> <str name="fq">WebSections_ms:house</str>              <str
>> name="fq">{!tag=current_group}GroupIds_ms:*</str>
>>              <str name="facet">true</str>             
<str
>> name="facet.field">BrandID_s</str>              <str
>> name="facet.query">Price_2_f:[* TO *]</str>              <str
>> name="facet.query">Price_3_f:[* TO *]</str>              <str
>> name="facet.query">Price_4_f:[* TO *]</str>              <str
>> name="facet.query">Price_5_f:[* TO *]</str>              <str
>> name="facet.query">Price_6_f:[* TO *]</str>              <str
>> name="facet.query">Price_7_f:[* TO *]</str>              <str
>> name="facet.query">Price_8_f:[* TO *]</str>              <str
>> name="facet.mincount">1</str>              <str name="facet.method">fc</str>
>> <str name="wt">json</str>              <str name="json.nl">map</str>
>> <str name="q">(title:* OR text:*)</str>              <str
>> name="start">0</str>              <str name="rows">20</str> 
      </lst>
>> </arr>  </listener>
>>
>> Console log:
>> INFO  (searcherExecutor-7-thread-1-processing-x:core1) [  x:core1]
>> o.a.s.c.S.Request [core1] webapp=null path=null
>> params={facet=true&facet.mincount=1&start=0&facet.query=Price_2_f:[*+TO+*]&facet.query=Price_3_f:[*+TO+*]&facet.query=Price_4_f:[*+TO+*]&facet.query=Price_5_f:[*+TO+*]&facet.query=Price_6_f:[*+TO+*]&facet.query=Price_7_f:[*+TO+*]&facet.query=Price_8_f:[*+TO+*]&event=newSearcher&q=(title:*+OR+text:*)&distrib=false&json.nl=map&facet.field=BrandID_s&wt=json&facet.method=fc&fq=DataType_s:Product&fq=WebSections_ms:house&fq=VisibleOnline_ms:7&fq={!tag%3Dcurrent_group}GroupIds_ms:*&rows=20}
>> hits=2549 status=0 QTime=1263
>>
>>
>> If I run the same query after the index has registered I see a QTime of
>> over a second, the second time I run the query I see around 80ms. This leads
>> me to believe the warming did not occur or the query was not commited to
>> cache on start up of the new searcher.
>> Can someone please advise on how to use the newSearcher queries to
>> effectively warm SolR caches. Should I see an improved response for the
>> first time I run the query if the same query has been used as a newSearcher
>> query?
>> Cheers,
>> Dalton
>
>

On Thu, Oct 6, 2016 at 4:43 PM, Dalton Gooding
<daltonwestcoast@yahoo.com.au> wrote:
> Erick,
>
> Thanks for the response. After I run the initial query and get a long
> response time, if I change the query to remove or add additional query
> statements, I find the speed is good.
>
> If I run the modified query after a new searcher has registered, the
> response is slow but after the modified query has been completed, the
> warming query sent from CuRl is much faster. I assume it is because the
> document cache has updated with the documents from the modified query. A
> large number of our queries work with the same document set, I am trying to
> get a warming query to populate the document cache to be as big as feasible.
>
> Should the firstSearcher and newSearcher warm the document cache?
>
>
> On Friday, 7 October 2016, 9:31, Erick Erickson <erickerickson@gmail.com>
> wrote:
>
>
> Submitting the exact same query twice will return results from the
> queryResultCache. I'm not entirely
> sure that the firstSearcher events get put into the cache.
>
> So if you change the query even slighty my guess is that you'll see
> response times very close to your
> original ones of over a second.
>
> Best,
> Erick
>
>
> On Thu, Oct 6, 2016 at 2:56 PM, Dalton Gooding
> <daltonwestcoast@yahoo.com.au.invalid> wrote:
>> After setting a number of newSearcher and firstSearcher queries, I can see
>> in the console logs that the queries are run, but when I run the same query
>> against the new searcher (using CuRL), I get a slow response time for the
>> first run.
>>
>> Config:
>>    <listener event="newSearcher" class="solr.QuerySenderListener">
>> <arr name="queries">        <lst> <str name="fq">DataType_s:Product</str>
>> <str name="fq">WebSections_ms:house</str>              <str
>> name="fq">{!tag=current_group}GroupIds_ms:*</str>
>>              <str name="facet">true</str>             
<str
>> name="facet.field">BrandID_s</str>              <str
>> name="facet.query">Price_2_f:[* TO *]</str>              <str
>> name="facet.query">Price_3_f:[* TO *]</str>              <str
>> name="facet.query">Price_4_f:[* TO *]</str>              <str
>> name="facet.query">Price_5_f:[* TO *]</str>              <str
>> name="facet.query">Price_6_f:[* TO *]</str>              <str
>> name="facet.query">Price_7_f:[* TO *]</str>              <str
>> name="facet.query">Price_8_f:[* TO *]</str>              <str
>> name="facet.mincount">1</str>              <str name="facet.method">fc</str>
>> <str name="wt">json</str>              <str name="json.nl">map</str>
>> <str name="q">(title:* OR text:*)</str>              <str
>> name="start">0</str>              <str name="rows">20</str> 
      </lst>
>> </arr>  </listener>
>>
>> Console log:
>> INFO  (searcherExecutor-7-thread-1-processing-x:core1) [  x:core1]
>> o.a.s.c.S.Request [core1] webapp=null path=null
>> params={facet=true&facet.mincount=1&start=0&facet.query=Price_2_f:[*+TO+*]&facet.query=Price_3_f:[*+TO+*]&facet.query=Price_4_f:[*+TO+*]&facet.query=Price_5_f:[*+TO+*]&facet.query=Price_6_f:[*+TO+*]&facet.query=Price_7_f:[*+TO+*]&facet.query=Price_8_f:[*+TO+*]&event=newSearcher&q=(title:*+OR+text:*)&distrib=false&json.nl=map&facet.field=BrandID_s&wt=json&facet.method=fc&fq=DataType_s:Product&fq=WebSections_ms:house&fq=VisibleOnline_ms:7&fq={!tag%3Dcurrent_group}GroupIds_ms:*&rows=20}
>> hits=2549 status=0 QTime=1263
>>
>>
>> If I run the same query after the index has registered I see a QTime of
>> over a second, the second time I run the query I see around 80ms. This leads
>> me to believe the warming did not occur or the query was not commited to
>> cache on start up of the new searcher.
>> Can someone please advise on how to use the newSearcher queries to
>> effectively warm SolR caches. Should I see an improved response for the
>> first time I run the query if the same query has been used as a newSearcher
>> query?
>> Cheers,
>> Dalton
>
>

   
Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message