I'm trying to understand what Solr is doing regarding commits based on the
logs below. I have a 60 second autocommit and no explicit commits coming in.
What I am seeing in my Solr log appears to be 2 (or 3?) commits per 60
second commit cycle. Everything below happens within a few seconds. I say
maybe 3 commits because the third one reports the same generation as the
second one. At any rate, I would expect to only see one commit and one group
of cache statistics, not three. Am I misinterpreting the logs?
Feb 27, 2012 6:22:36 AM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: start
commit(optimize=false,waitFlush=false,waitSearcher=true,expungeDeletes=false)
Feb 27, 2012 6:22:37 AM org.apache.solr.core.SolrDeletionPolicy onCommit
INFO: SolrDeletionPolicy.onCommit: commits:num=2
commit{dir=/home/search/solr/solr/data/index,segFN=segments_141z,version=1328113878743,generation=51911,filenames=[_189h.tii,
_189f.frq, _189h.fdx, _17nb.fdx, _187m.tis, _17nb.fdt, _187w_3.del,
_187m.tii, _187w.fdt, _17nb.tis, _1894.tii, _189f.prx, _189l.tii, _187w.fdx,
_189k.frq, _189n.frq, _1887.frq, _1894.tis, _189n.fdt, _1887.prx, _189n.fdx,
_16q1.nrm, _189i.frq, _189l.frq, _1887.tii, _17nb.prx, _189f_1.del,
_189f.nrm, _187m.prx, _187m.fnm, _189j.fnm, _1894.nrm, _189i.tii, _1887.tis,
_17nb.tii, _189h.fdt, _1887.nrm, _189m.fnm, _16q1.frq, _189l.nrm, _189k.prx,
_189i.prx, _189n_1.del, _17nb.fnm, _189i.tis, _16q1.tii, _17nb_2f.del,
_189j.nrm, _16q1.tis, _189j.fdt, _189m.fdt, _189i_1.del, _187w.frq,
_189m.fdx, _189i.nrm, segments_141z, _189g.frq, _188s.frq, _187m.nrm,
_187f.tii, _1894.prx, _189l.tis, _189j.fdx, _189k.nrm, _1894.fnm, _187w.tii,
_187f.prx, _187f.fnm, _189j.prx, _187w.tis, _189h.fnm, _188t.frq, _189h.tis,
_1894.fdt, _187f.frq, _189n.fnm, _187f.fdx, _1894.fdx, _189l.prx, _187w.nrm,
_188s.nrm, _189h.frq, _1887.fnm, _187f.tis, _16q1.prx, _189j.tis, _188s.prx,
_189k_1.del, _189l.fnm, _189f.fnm, _189h.prx, _189k.fnm, _188s_1.del,
_189n.prx, _189m.nrm, _189n.nrm, _189g.tii, _188t.tis, _16q1.fdt, _189i.fnm,
_1887.fdx, _189m.prx, _16q1_1a5.del, _189j.frq, _189n.tis, _1887.fdt,
_187w.prx, _187f.nrm, _189f.fdt, _189f.tis, _189k.tis, _189f.fdx, _189m.frq,
_16q1.fdx, _187m.frq, _189n.tii, _187f.fdt, _188t.tii, _189m.tis, _17nb.frq,
_189k.fdt, _189j_1.del, _189g.prx, _189k.tii, _189k.fdx, _189g.fdx,
_16q1.fnm, _189m.tii, _189g.nrm, _189f.tii, _189m_1.del, _187w.fnm,
_187m.fdt, _188t.fdt, _1894.frq, _189g.fnm, _188t.nrm, _189i.fdx, _188t.fdx,
_188t.prx, _189h.nrm, _189g.tis, _187m.fdx, _188s.fnm, _189i.fdt, _188s.tis,
_17nb.nrm, _189j.tii, _188t.fnm, _187f_5.del, _189l.fdx, _189g.fdt,
_188s.fdx, _189l.fdt, _188s.tii, _188s.fdt]
commit{dir=/home/search/solr/solr/data/index,segFN=segments_1420,version=1328113878746,generation=51912,filenames=[_189h.tii,
_189f.frq, _189h.fdx, _17nb.fdx, _187m.tis, _17nb.fdt, _187w_3.del,
_187m.tii, _187w.fdt, _17nb.tis, _1894.tii, _189o.tii, _189f.prx, _189l.tii,
_187w.fdx, _189k.frq, _189n.frq, _1887.frq, _1894.tis, _189n.fdt, _1887.prx,
_189o.tis, _189n.fdx, _16q1.nrm, _189o.frq, _189i.frq, _189l.frq, _1887.tii,
_17nb.prx, _189f_1.del, _189f.nrm, _187m.prx, _187m.fnm, _189o.prx,
_189j.fnm, _1894.nrm, _189i.tii, _1887.tis, _17nb.tii, _189h.fdt, _1887.nrm,
_189m.fnm, _16q1.frq, _189l.nrm, _189k.prx, _189i.prx, _189n_1.del,
_17nb.fnm, _189i.tis, _16q1.tii, _17nb_2f.del, _189o.fdt, _189j.nrm,
_16q1.tis, _189j.fdt, _189m.fdt, _189i_1.del, _187w.frq, _189o.fdx,
_189m.fdx, _189i.nrm, _189g.frq, _188s.frq, _187m.nrm, _187f.tii, _1894.prx,
_189l.tis, _189j.fdx, _189k.nrm, _1894.fnm, _187w.tii, _187f.prx, _187f.fnm,
_189j.prx, _187w.tis, _189h.fnm, _188t.frq, _189h.tis, _1894.fdt, _187f.frq,
_189n.fnm, _187f.fdx, _1894.fdx, _189l.prx, _187w.nrm, _188s.nrm, _189h.frq,
_1887.fnm, _187f.tis, _16q1.prx, _189j.tis, _188s.prx, _189k_1.del,
_189l.fnm, _189f.fnm, _189h.prx, _189k.fnm, _188s_1.del, _189n.prx,
_189m.nrm, _189n.nrm, _189g.tii, _188t.tis, segments_1420, _16q1.fdt,
_189o.fnm, _189i.fnm, _1887.fdx, _189m.prx, _189j.frq, _189n.tis, _1887.fdt,
_187w.prx, _187f.nrm, _189f.fdt, _189f.tis, _189k.tis, _189f.fdx, _189m.frq,
_16q1.fdx, _187m.frq, _189n.tii, _187f.fdt, _188t.tii, _189m.tis, _17nb.frq,
_189k.fdt, _189j_1.del, _189g.prx, _189k.tii, _189k.fdx, _189g.fdx,
_16q1.fnm, _189m.tii, _189g.nrm, _189f.tii, _189m_1.del, _187w.fnm,
_187m.fdt, _188t.fdt, _1894.frq, _189g.fnm, _188t.nrm, _189i.fdx, _188t.fdx,
_188t.prx, _189h.nrm, _189g.tis, _187m.fdx, _188s.fnm, _189i.fdt,
_16q1_1a6.del, _188s.tis, _17nb.nrm, _189j.tii, _188t.fnm, _187f_5.del,
_189l.fdx, _189g.fdt, _188s.fdx, _189o.nrm, _189l.fdt, _188s.tii, _188s.fdt]
Feb 27, 2012 6:22:37 AM org.apache.solr.core.SolrDeletionPolicy
updateCommits
INFO: newest commit = 1328113878746
Feb 27, 2012 6:22:37 AM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@14d3aabd main
Feb 27, 2012 6:22:37 AM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: end_commit_flush
Feb 27, 2012 6:22:37 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@14d3aabd main from Searcher@8f0adad main
fieldValueCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 27, 2012 6:22:37 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@14d3aabd main
fieldValueCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 27, 2012 6:22:37 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@14d3aabd main from Searcher@8f0adad main
filterCache{lookups=1686,hits=1528,hitratio=0.90,inserts=158,evictions=0,size=1175,warmupTime=4217,cumulative_lookups=8757745,cumulative_hits=8066991,cumulative_hitratio=0.92,cumulative_inserts=690754,cumulative_evictions=0}
Feb 27, 2012 6:22:38 AM org.apache.solr.core.SolrDeletionPolicy onInit
INFO: SolrDeletionPolicy.onInit: commits:num=1
commit{dir=/home/search/solr/solr/data/index,segFN=segments_1420,version=1328113878746,generation=51912,filenames=[_189h.tii,
_189f.frq, _189h.fdx, _17nb.fdx, _187m.tis, _17nb.fdt, _187w_3.del,
_187m.tii, _187w.fdt, _17nb.tis, _1894.tii, _189o.tii, _189f.prx, _189l.tii,
_187w.fdx, _189k.frq, _189n.frq, _1887.frq, _1894.tis, _189n.fdt, _1887.prx,
_189o.tis, _189n.fdx, _16q1.nrm, _189o.frq, _189i.frq, _189l.frq, _1887.tii,
_17nb.prx, _189f_1.del, _189f.nrm, _187m.prx, _187m.fnm, _189o.prx,
_189j.fnm, _1894.nrm, _189i.tii, _1887.tis, _17nb.tii, _189h.fdt, _1887.nrm,
_189m.fnm, _16q1.frq, _189l.nrm, _189k.prx, _189i.prx, _189n_1.del,
_17nb.fnm, _189i.tis, _16q1.tii, _17nb_2f.del, _189o.fdt, _189j.nrm,
_16q1.tis, _189j.fdt, _189m.fdt, _189i_1.del, _187w.frq, _189o.fdx,
_189m.fdx, _189i.nrm, _189g.frq, _188s.frq, _187m.nrm, _187f.tii, _1894.prx,
_189l.tis, _189j.fdx, _189k.nrm, _1894.fnm, _187w.tii, _187f.prx, _187f.fnm,
_189j.prx, _187w.tis, _189h.fnm, _188t.frq, _189h.tis, _1894.fdt, _187f.frq,
_189n.fnm, _187f.fdx, _1894.fdx, _189l.prx, _187w.nrm, _188s.nrm, _189h.frq,
_1887.fnm, _187f.tis, _16q1.prx, _189j.tis, _188s.prx, _189k_1.del,
_189l.fnm, _189f.fnm, _189h.prx, _189k.fnm, _188s_1.del, _189n.prx,
_189m.nrm, _189n.nrm, _189g.tii, _188t.tis, segments_1420, _16q1.fdt,
_189o.fnm, _189i.fnm, _1887.fdx, _189m.prx, _189j.frq, _189n.tis, _1887.fdt,
_187w.prx, _187f.nrm, _189f.fdt, _189f.tis, _189k.tis, _189f.fdx, _189m.frq,
_16q1.fdx, _187m.frq, _189n.tii, _187f.fdt, _188t.tii, _189m.tis, _17nb.frq,
_189k.fdt, _189j_1.del, _189g.prx, _189k.tii, _189k.fdx, _189g.fdx,
_16q1.fnm, _189m.tii, _189g.nrm, _189f.tii, _189m_1.del, _187w.fnm,
_187m.fdt, _188t.fdt, _1894.frq, _189g.fnm, _188t.nrm, _189i.fdx, _188t.fdx,
_188t.prx, _189h.nrm, _189g.tis, _187m.fdx, _188s.fnm, _189i.fdt,
_16q1_1a6.del, _188s.tis, _17nb.nrm, _189j.tii, _188t.fnm, _187f_5.del,
_189l.fdx, _189g.fdt, _188s.fdx, _189o.nrm, _189l.fdt, _188s.tii, _188s.fdt]
Feb 27, 2012 6:22:38 AM org.apache.solr.core.SolrDeletionPolicy
updateCommits
INFO: newest commit = 1328113878746
Feb 27, 2012 6:22:41 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@14d3aabd main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=1024,warmupTime=4302,cumulative_lookups=8757984,cumulative_hits=8067213,cumulative_hitratio=0.92,cumulative_inserts=690771,cumulative_evictions=0}
Feb 27, 2012 6:22:41 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@14d3aabd main from Searcher@8f0adad main
queryResultCache{lookups=454,hits=52,hitratio=0.11,inserts=402,evictions=0,size=406,warmupTime=0,cumulative_lookups=2122786,cumulative_hits=304610,cumulative_hitratio=0.14,cumulative_inserts=1818261,cumulative_evictions=0}
Feb 27, 2012 6:22:41 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@14d3aabd main
queryResultCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=2122786,cumulative_hits=304610,cumulative_hitratio=0.14,cumulative_inserts=1818261,cumulative_evictions=0}
Feb 27, 2012 6:22:41 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@14d3aabd main from Searcher@8f0adad main
documentCache{lookups=716,hits=533,hitratio=0.74,inserts=183,evictions=0,size=183,warmupTime=0,cumulative_lookups=3052040,cumulative_hits=2346594,cumulative_hitratio=0.76,cumulative_inserts=705446,cumulative_evictions=0}
Feb 27, 2012 6:22:41 AM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@14d3aabd main
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=3052040,cumulative_hits=2346594,cumulative_hitratio=0.76,cumulative_inserts=705446,cumulative_evictions=0}
INFO: QuerySenderListener sending requests to Searcher@14d3aabd main
INFO: QuerySenderListener done.
Feb 27, 2012 6:22:41 AM org.apache.solr.core.SolrCore registerSearcher
INFO: [] Registered new searcher Searcher@14d3aabd main
Feb 27, 2012 6:22:41 AM org.apache.solr.search.SolrIndexSearcher close
INFO: Closing Searcher@8f0adad main
fieldValueCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,warmupTime=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
filterCache{lookups=1925,hits=1750,hitratio=0.90,inserts=175,evictions=0,size=1190,warmupTime=4217,cumulative_lookups=8757984,cumulative_hits=8067213,cumulative_hitratio=0.92,cumulative_inserts=690771,cumulative_evictions=0}
queryResultCache{lookups=454,hits=52,hitratio=0.11,inserts=402,evictions=0,size=406,warmupTime=0,cumulative_lookups=2122786,cumulative_hits=304610,cumulative_hitratio=0.14,cumulative_inserts=1818261,cumulative_evictions=0}
documentCache{lookups=716,hits=533,hitratio=0.74,inserts=183,evictions=0,size=183,warmupTime=0,cumulative_lookups=3052040,cumulative_hits=2346594,cumulative_hitratio=0.76,cumulative_inserts=705446,cumulative_evictions=0}
--
View this message in context:
http://lucene.472066.n3.nabble.com/General-question-on-understanding-Solr-log-output-tp3779907p3779907.html
Sent from the Solr - User mailing list archive at Nabble.com.
