Running lastnights apachesolr, version 6 from SVN of the php client and RC2.

When I index documents, I get the error below several times, I am not sure how to get more information out of solr. I am using the current schema and solr config file.

SEVERE: java.lang.ArrayIndexOutOfBoundsException: -1
at org.apache.solr.analysis.TrimFilter.incrementToken(TrimFilter.java:63)
at org.apache.lucene.index.DocInverterPerField.processFields(DocInverterPerField.java:138)
at org.apache.lucene.index.DocFieldProcessorPerThread.processDocument(DocFieldProcessorPerThread.java:244)
at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:772)
at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:755)
at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:2613)
at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:2585)
at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:240)
at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:61)
at org.apache.solr.handler.XMLLoader.processUpdate(XMLLoader.java:140)
at org.apache.solr.handler.XMLLoader.load(XMLLoader.java:69)
at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:54)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:131)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:1299)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:338)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:241)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
at org.hyperic.hq.product.servlet.filter.JMXFilter.doFilter(JMXFilter.java:324)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:174)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:874)
at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665)
at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528)
at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)
at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:689)
at java.lang.Thread.run(Thread.java:595)

CommentFileSizeAuthor
#11 apachesolr_error.txt7.34 KBaether

Comments

drupalnuts’s picture

drupalnuts commented

More log entries if it helps
INFO: [] webapp=/solr path=/admin/ping params={} status=0 QTime=3
Aug 18, 2009 4:09:37 PM org.apache.solr.core.SolrCore execute
INFO: [] webapp=/solr path=/admin/luke params={wt=json&numTerms=0} status=0 QTime=1
Aug 18, 2009 4:09:50 PM org.apache.solr.core.SolrCore execute
INFO: [] webapp=/solr path=/admin/ping params={} hits=0 status=0 QTime=2
Aug 18, 2009 4:09:50 PM org.apache.solr.core.SolrCore execute
INFO: [] webapp=/solr path=/admin/ping params={} status=0 QTime=2
Aug 18, 2009 4:09:56 PM org.apache.solr.core.SolrDeletionPolicy onInit
INFO: SolrDeletionPolicy.onInit: commits:num=1
commit{dir=/home/solr/data/index,segFN=segments_7,version=1250623113166,generation=7,filenames=[_3.prx, _3.nrm, _3.tis, _3.frq, _3.fnm, _3.fdt, _3_1.del, _3.fdx, _3.tii, segments_7]
Aug 18, 2009 4:09:56 PM org.apache.solr.core.SolrDeletionPolicy updateCommits
INFO: last commit = 1250623113166
Aug 18, 2009 4:09:56 PM org.apache.solr.update.processor.LogUpdateProcessor finish
INFO: {} 0 46
Aug 18, 2009 4:09:56 PM org.apache.solr.common.SolrException log
SEVERE: java.lang.ArrayIndexOutOfBoundsException: -1
at org.apache.solr.analysis.TrimFilter.incrementToken(TrimFilter.java:63)
at org.apache.lucene.index.DocInverterPerField.processFields(DocInverterPerField.java:138)
at org.apache.lucene.index.DocFieldProcessorPerThread.processDocument(DocFieldProcessorPerThread.java:244)
at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:772)
at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:755)
at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:2613)
at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:2585)
at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:240)
at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:61)
at org.apache.solr.handler.XMLLoader.processUpdate(XMLLoader.java:140)
at org.apache.solr.handler.XMLLoader.load(XMLLoader.java:69)
at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:54)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:131)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:1299)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:338)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:241)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
at org.hyperic.hq.product.servlet.filter.JMXFilter.doFilter(JMXFilter.java:324)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:174)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:874)
at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665)
at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528)
at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)
at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:689)
at java.lang.Thread.run(Thread.java:595)

Aug 18, 2009 4:09:56 PM org.apache.solr.core.SolrCore execute
INFO: [] webapp=/solr path=/update params={wt=json} status=500 QTime=46
Aug 18, 2009 4:09:56 PM org.apache.solr.common.SolrException log
SEVERE: java.lang.ArrayIndexOutOfBoundsException: -1
at org.apache.solr.analysis.TrimFilter.incrementToken(TrimFilter.java:63)
at org.apache.lucene.index.DocInverterPerField.processFields(DocInverterPerField.java:138)
at org.apache.lucene.index.DocFieldProcessorPerThread.processDocument(DocFieldProcessorPerThread.java:244)
at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:772)
at org.apache.lucene.index.DocumentsWriter.updateDocument(DocumentsWriter.java:755)
at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:2613)
at org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:2585)
at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:240)
at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:61)
at org.apache.solr.handler.XMLLoader.processUpdate(XMLLoader.java:140)
at org.apache.solr.handler.XMLLoader.load(XMLLoader.java:69)
at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:54)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:131)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:1299)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:338)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:241)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
at org.hyperic.hq.product.servlet.filter.JMXFilter.doFilter(JMXFilter.java:324)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:174)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:874)
at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665)
at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528)
at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81)
at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:689)
at java.lang.Thread.run(Thread.java:595)

Aug 18, 2009 4:09:56 PM org.apache.solr.core.SolrCore execute
INFO: [] webapp=/solr path=/admin/ping params={} hits=0 status=0 QTime=2
Aug 18, 2009 4:09:56 PM org.apache.solr.core.SolrCore execute
INFO: [] webapp=/solr path=/admin/ping params={} status=0 QTime=2
Aug 18, 2009 4:09:56 PM org.apache.solr.core.SolrCore execute
INFO: [] webapp=/solr path=/admin/luke params={wt=json&numTerms=0} status=0 QTime=1

MVRider’s picture

MVRider commented

I just installed this module as well and I get the same error.

marthinal’s picture

marthinal commented

Me too the same when running cron "The search index is generated by running cron. 0% of the site content has been sent to the server. There are 604 items left to send. " all the time.

glueckskind’s picture

glueckskind commented

Here the same.

robertdouglass’s picture

robertdouglass commented

Are you all running 6.x-1.0-rc2? Or the dev version? Or the 6.x-2.0 branch? Can you try with a Solr from a few nights ago? And... are you sure you're copying the schema.xml and solrconfig.xml and restarting the solr server? It's very weird that the ApacheSolr module RC2 would just stop working. We need to isolate the moving parts.

drupalnuts’s picture

drupalnuts commented
Status: Active » Closed (won't fix)

Rob,

You are correct, it seems to be an issue with solr. I pulled the version for the middle of last week, and everything works great now.

Thanks

katbailey’s picture

katbailey commented
Status: Closed (won't fix) » Active

Setting this back to active, as obviously finding an earlier build of Solr isn't much of a solution to a major problem like this.

robertdouglass’s picture

robertdouglass commented

So I too am running off of nightly builds. In fact I update my build regularly to make sure I catch things that move. But I'm not encountering the error, which makes it hard to diagnose.

katbailey’s picture

katbailey commented

Ah, my understanding was that this was a problem with all nightly builds since some time earlier this month. The current project I'm working on was using a build from late June in the dev environment - we just set up the production environment yesterday using a Solr build from yesterday and I got this error. I reverted it back to the build from June for now and it's running smoothly.

robertdouglass’s picture

robertdouglass commented

I just wish those damn stack traces gave any indication as to what the actual error is. :(

aether’s picture

aether commented
StatusFileSize
new apachesolr_error.txt7.34 KB

Just want to chime in and say I am experiencing a similar error as well although mine specifies:

Indexing failed on one of the following nodes: 808, 809, 810, 2059, 2060, 2061, 2062, 2063, 1448, 1449, 1450, 1451, 1452, 1453, 1454, 1455, 1456, 1457, 1458, 1459
"500" Status: Internal Server ErrorApache Tomcat/6.0.20 - Error report HTTP Status 500 - -1

Running RC2 and latest solr nightly build (as of yesterday). Looking at my logs it appears this error also appeared earlier today with a different list of nodes and then cleared itself up. This is my first solr install and is less than a day old so I don't have any history beyond that.

I have attached a full copy of the error detail.

pwolanin’s picture

pwolanin commented

I filed an issue against Solr itself https://issues.apache.org/jira/browse/SOLR-1400

JIspahany’s picture

JIspahany commented

Hi Guys,

Sorry for the ignorance im new to this.

Same thing happened to me last night. Installed, followed instructions.

As soon as I tried to Index I restarted Solr and it broke (the same errors above)

I used

apache-solr-1.3.0.zip -> 15-Sep-2008 17:38

bexecho’s picture

bexecho commented
Status: Active » Needs review

Looks like Solr fixed the issue on their end this morning (revision 812494). I was able to reproduce the problem using the nightly build from last night (Sept 8, 2009). I just did a build from trunk and indexing worked. I imagine tonight's build should work again once it is posted.

pwolanin’s picture

pwolanin commented
Status: Needs review » Fixed

Should be - looked like the fix was committed today to Solr.

Status: Fixed » Closed (fixed)

Automatically closed -- issue fixed for 2 weeks with no activity.