You are viewing a plain text version of this content. The canonical link for it is here.
Posted to solr-dev@lucene.apache.org by Yonik Seeley <yo...@apache.org> on 2007/02/04 23:07:31 UTC
AutoCommitTest failing
With the latest changes to AutoCommitTest, now I'm getting failures:
[junit] Tests run: 2, Failures: 1, Errors: 0, Time elapsed: 5.593 sec
[junit] Test org.apache.solr.update.AutoCommitTest FAILED
-Yonik
Re: AutoCommitTest failing
Posted by Ryan McKinley <ry...@gmail.com>.
I just posted a simple change on SOLR-126... it cancels any pending
activity when you close the UpdateHandler
public void close() throws IOException {
log.info("closing " + this);
iwCommit.lock();
try{
// cancel any pending operations
if( tracker.pending != null ) {
tracker.pending.cancel( true );
tracker.pending = null;
}
doDeletions();
closeSearcher();
closeWriter();
} finally {
iwCommit.unlock();
}
log.info("closed " + this);
}
Re: AutoCommitTest failing
Posted by Mike Klaas <mi...@gmail.com>.
On 2/5/07, Yonik Seeley <yo...@apache.org> wrote:
> On 2/5/07, Mike Klaas <mi...@gmail.com> wrote:
> > On 2/5/07, Yonik Seeley <yo...@apache.org> wrote:
> >
> > > That's probably a good idea.
> > >
> > > I was able to get the current version to fail on my PC at work
> > > intermittently by running something else that was eating CPU.
> >
> > Could you try the latest trunk? It should block on pending commits if
> > they happen to take too long.
>
> Tried it a few times, no failures yet.
> Thanks!
Phew!
-Mike
Re: AutoCommitTest failing
Posted by Yonik Seeley <yo...@apache.org>.
On 2/5/07, Mike Klaas <mi...@gmail.com> wrote:
> On 2/5/07, Yonik Seeley <yo...@apache.org> wrote:
>
> > That's probably a good idea.
> >
> > I was able to get the current version to fail on my PC at work
> > intermittently by running something else that was eating CPU.
>
> Could you try the latest trunk? It should block on pending commits if
> they happen to take too long.
Tried it a few times, no failures yet.
Thanks!
-Yonik
Re: AutoCommitTest failing
Posted by Mike Klaas <mi...@gmail.com>.
On 2/5/07, Yonik Seeley <yo...@apache.org> wrote:
> That's probably a good idea.
>
> I was able to get the current version to fail on my PC at work
> intermittently by running something else that was eating CPU.
Could you try the latest trunk? It should block on pending commits if
they happen to take too long.
I'm having an exceptionally hard time getting the old version to fail,
but I haven't seen this version fail yet.
-Mike
Re: AutoCommitTest failing
Posted by Ryan McKinley <ry...@gmail.com>.
> >
> > Perhaps we should increase the alloted time _and_ check explicitly if
> > an egregious amount of time has passed in the unittest. If so, we can
> > skip the test instead of failing it.
>
> That's probably a good idea.
>
> I was able to get the current version to fail on my PC at work
> intermittently by running something else that was eating CPU.
>
Is there a way to output a warning - but not a failure?
The problem with not failing when an egregious about of time passes is
that that is exactly what the test is looking for!
Re: AutoCommitTest failing
Posted by Yonik Seeley <yo...@apache.org>.
On 2/5/07, Mike Klaas <mi...@gmail.com> wrote:
> On 2/4/07, Ryan McKinley <ry...@gmail.com> wrote:
> > hymmm. what do you think the best option is?
> >
> > Should we increase the time it waits to check if stuff autocommited?
> > It currently waits 1 second for something that *should* start an
> > autocommit within 1/2 sec.
>
> Perhaps we should increase the alloted time _and_ check explicitly if
> an egregious amount of time has passed in the unittest. If so, we can
> skip the test instead of failing it.
That's probably a good idea.
I was able to get the current version to fail on my PC at work
intermittently by running something else that was eating CPU.
-Yonik
Re: AutoCommitTest failing
Posted by Mike Klaas <mi...@gmail.com>.
On 2/4/07, Ryan McKinley <ry...@gmail.com> wrote:
> hymmm. what do you think the best option is?
>
> Should we increase the time it waits to check if stuff autocommited?
> It currently waits 1 second for something that *should* start an
> autocommit within 1/2 sec.
Perhaps we should increase the alloted time _and_ check explicitly if
an egregious amount of time has passed in the unittest. If so, we can
skip the test instead of failing it.
-Mike
Re: AutoCommitTest failing
Posted by Ryan McKinley <ry...@gmail.com>.
hymmm. what do you think the best option is?
Should we increase the time it waits to check if stuff autocommited?
It currently waits 1 second for something that *should* start an
autocommit within 1/2 sec.
Is the RejectedExecutionException only thrown because JUnit failed?
or is this an exception that would be thrown in the wild?
Re: AutoCommitTest failing
Posted by Yonik Seeley <yo...@apache.org>.
On 2/4/07, Yonik Seeley <yo...@apache.org> wrote:
> OK, the wildcard seems to be norton antivirus.
>
> Antivirus on:
> BUILD FAILED
> Total time: 2 minutes 22 seconds
>
> Antivirus off:
> BUILD SUCCESSFUL
> Total time: 1 minute 24 seconds
>
> Not yet sure why that would make the difference... and only on this test.
Ah wait.. it still is timing/performance related.
With NAV disabled, and one other shell doing "while true; do true;
done", the test succeeds in 2m2s, but with two shells doing it, the
test fails in 9m1s
So I guess my antivirus is slowing my 3GHz P4 down enough to fail.
It probably has more to do with the disk access checking than the CPU.
[junit] Tests run: 2, Failures: 2, Errors: 0, Time elapsed: 10.687 sec
[junit] Test org.apache.solr.update.AutoCommitTest FAILED
<?xml version="1.0" encoding="UTF-8" ?>
<testsuite errors="0" failures="2"
name="org.apache.solr.update.AutoCommitTest" tests="2" time="10.687">
<properties>
<property name="java.vendor" value="Sun Microsystems Inc."></property>
<property name="sun.management.compiler" value="HotSpot Client
Compiler"></property>
<property name="dist" value="dist"></property>
<property name="lib" value="lib"></property>
<property name="os.name" value="Windows XP"></property>
<property name="sun.boot.class.path"
value="C:\opt\jdk15\jre\lib\rt.jar;C:\opt\jdk15\jre\lib\i18n.jar;C:\opt\jdk15\jre\lib\sunrsasign.jar;C:\opt\jdk15\jre\lib\jsse.jar;C:\opt\jdk15\jre\lib\jce.jar;C:\opt\jdk15\jre\lib\charsets.jar;C:\opt\jdk15\jre\classes"></property>
<property name="fullnamever" value="apache-solr-1.2-dev"></property>
<property name="TODAY" value="February 4 2007"></property>
<property name="sun.desktop" value="windows"></property>
<property name="java.vm.specification.vendor" value="Sun
Microsystems Inc."></property>
<property name="ant.home" value="c:/opt/java/ant"></property>
<property name="java.runtime.version" value="1.5.0_09-b01"></property>
<property name="user.name" value="Yonik"></property>
<property name="dest" value="build"></property>
<property name="user.language" value="en"></property>
<property name="build.javadoc" value="build/docs/api"></property>
<property name="sun.boot.library.path"
value="C:\opt\jdk15\jre\bin"></property>
<property name="ant.project.name" value="solr"></property>
<property name="DSTAMP" value="2007-02-04"></property>
<property name="java.version" value="1.5.0_09"></property>
<property name="version" value="1.2-dev"></property>
<property name="user.timezone" value=""></property>
<property name="sun.arch.data.model" value="32"></property>
<property name="java.endorsed.dirs"
value="C:\opt\jdk15\jre\lib\endorsed"></property>
<property name="sun.cpu.isalist" value=""></property>
<property name="sun.jnu.encoding" value="Cp1252"></property>
<property name="file.encoding.pkg" value="sun.io"></property>
<property name="file.separator" value="\"></property>
<property name="java.specification.name" value="Java Platform API
Specification"></property>
<property name="javadoc.packages" value="org.apache.solr.*"></property>
<property name="java.class.version" value="49.0"></property>
<property name="src" value="src"></property>
<property name="user.country" value="US"></property>
<property name="java.home" value="C:\opt\jdk15\jre"></property>
<property name="java.vm.info" value="mixed mode"></property>
<property name="ant.file" value="c:\tmp555\solr\build.xml"></property>
<property name="os.version" value="5.1"></property>
<property name="javadoc.access" value="protected"></property>
<property name="path.separator" value=";"></property>
<property name="specversion" value="1.1.2007.02.04.23.20.45"></property>
<property name="java.vm.version" value="1.5.0_09-b01"></property>
<property name="java.compat.version" value="1.5"></property>
<property name="user.variant" value=""></property>
<property name="ant.library.dir" value="c:/opt/java/ant/lib"></property>
<property name="junit.output.dir"
value="C:\tmp555\solr\build\test-results"></property>
<property name="java.awt.printerjob"
value="sun.awt.windows.WPrinterJob"></property>
<property name="sun.io.unicode.encoding" value="UnicodeLittle"></property>
<property name="awt.toolkit" value="sun.awt.windows.WToolkit"></property>
<property name="javadoc.link.lucene"
value="http://lucene.apache.org/java/docs/api/"></property>
<property name="user.home" value="C:\Documents and
Settings\Yonik"></property>
<property name="ant.file.solr" value="C:\tmp555\solr\build.xml"></property>
<property name="java.specification.vendor" value="Sun Microsystems
Inc."></property>
<property name="java.library.path"
value="C:\opt\jdk15\jre\bin;.;C:\WINDOWS\system32;C:\WINDOWS;c:\opt\vcpp\bin;c:\opt\jdk15\bin;C:\cygwin\usr\local\bin;C:\cygwin\bin;C:\cygwin\bin;C:\cygwin\usr\X11R6\bin;c:\opt\ruby\bin;c:\WINDOWS\system32;c:\WINDOWS;c:\WINDOWS\System32\Wbem;c:\bin;c:\opt\python;c:\opt\vim\vim63;c:\Program
Files\ATI Technologies\ATI Control Panel;c:\Program
Files\QuickTime\QTSystem\;c:\opt\mysql\bin;c:\opt\java\ant\bin;c:\opt\ruby\bin;c:\opt\maven\bin"></property>
<property name="java.vendor.url" value="http://java.sun.com/"></property>
<property name="checksum.algorithm" value="md5"></property>
<property name="java.vm.vendor" value="Sun Microsystems Inc."></property>
<property name="java.runtime.name" value="Java(TM) 2 Runtime
Environment, Standard Edition"></property>
<property name="java.class.path"
value="C:\tmp555\solr\lib\commons-fileupload-20070107.jar;C:\tmp555\solr\lib\commons-io-1.2.jar;C:\tmp555\solr\lib\lucene-core-nightly.jar;C:\tmp555\solr\lib\lucene-highlighter-nightly.jar;C:\tmp555\solr\lib\lucene-snowball-nightly.jar;C:\tmp555\solr\lib\servlet-api-2.4.jar;C:\tmp555\solr\lib\xpp3-1.1.3.4.O.jar;C:\tmp555\solr\build;C:\tmp555\solr\build\tests;C:\tmp555\solr;C:\opt\java\ant\lib\junit.jar;C:\opt\java\ant\lib\ant-launcher.jar;C:\opt\java\ant\lib\ant.jar;C:\opt\java\ant\lib\ant-junit.jar"></property>
<property name="year" value="2007"></property>
<property name="ant.version" value="Apache Ant version 1.6.5
compiled on June 2 2005"></property>
<property name="example" value="example"></property>
<property name="dateversion" value="2007.02.04.23.20.45"></property>
<property name="TSTAMP" value="23:20:45"></property>
<property name="junit.includes"
value="**/Test*.java,**/*Test.java"></property>
<property name="java.vm.specification.name" value="Java Virtual
Machine Specification"></property>
<property name="java.vm.specification.version" value="1.0"></property>
<property name="sun.cpu.endian" value="little"></property>
<property name="sun.os.patch.level" value="Service Pack 2"></property>
<property name="java.io.tmpdir"
value="c:\DOCUME~1\Yonik\LOCALS~1\Temp\"></property>
<property name="cygwin.user.home" value="c:/yonik"></property>
<property name="java.vendor.url.bug"
value="http://java.sun.com/cgi-bin/bugreport.cgi"></property>
<property name="Name" value="Solr"></property>
<property name="javadoc.link.junit"
value="http://junit.sourceforge.net/javadoc/"></property>
<property name="junit.reports"
value="C:\tmp555\solr\build\test-results\reports"></property>
<property name="javadoc.years" value="2006 - 2007"></property>
<property name="java.awt.graphicsenv"
value="sun.awt.Win32GraphicsEnvironment"></property>
<property name="os.arch" value="x86"></property>
<property name="java.ext.dirs" value="C:\opt\jdk15\jre\lib\ext"></property>
<property name="user.dir"
value="C:\tmp555\solr\src\test\test-files"></property>
<property name="build.docs" value="build/docs"></property>
<property name="javadoc.link.java"
value="http://java.sun.com/j2se/1.5.0/docs/api/"></property>
<property name="fullname" value="apache-solr"></property>
<property name="line.separator" value="
"></property>
<property name="java.vm.name" value="Java HotSpot(TM) Client VM"></property>
<property name="basedir" value="C:\tmp555\solr"></property>
<property name="ant.java.version" value="1.5"></property>
<property name="file.encoding" value="Cp1252"></property>
<property name="junitPresent" value="true"></property>
<property name="java.specification.version" value="1.5"></property>
</properties>
<testcase classname="org.apache.solr.update.AutoCommitTest"
name="testMaxDocs" time="6.5">
<failure message="should find one query failed XPath:
//result[@numFound=1] xml response was: <?xml
version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int
name="status">0</int><int
name="QTime">0</int></lst><result
name="response" numFound="0"
start="0"/>
</response>
" type="junit.framework.AssertionFailedError">junit.framework.AssertionFailedError:
should find one query failed XPath: //result[@numFound=1] xml response
was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int
name="status">0</int><int
name="QTime">0</int></lst><result
name="response" numFound="0"
start="0"/>
</response>
at org.apache.solr.util.AbstractSolrTestCase.assertQ(AbstractSolrTestCase.java:157)
at org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:98)
</failure>
</testcase>
<testcase classname="org.apache.solr.update.AutoCommitTest"
name="testMaxTime" time="4.094">
<failure message="should find one query failed XPath:
//result[@numFound=1] xml response was: <?xml
version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int
name="status">0</int><int
name="QTime">0</int></lst><result
name="response" numFound="0"
start="0"/>
</response>
" type="junit.framework.AssertionFailedError">junit.framework.AssertionFailedError:
should find one query failed XPath: //result[@numFound=1] xml response
was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int
name="status">0</int><int
name="QTime">0</int></lst><result
name="response" numFound="0"
start="0"/>
</response>
at org.apache.solr.util.AbstractSolrTestCase.assertQ(AbstractSolrTestCase.java:157)
at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:150)
</failure>
</testcase>
<system-out><![CDATA[]]></system-out>
<system-err><![CDATA[Feb 4, 2007 11:28:35 PM
org.apache.solr.core.Config getInstanceDir
INFO: Solr home defaulted to 'solr/' (system property solr.solr.home not set)
Feb 4, 2007 11:28:35 PM org.apache.solr.core.SolrConfig initConfig
INFO: Loaded SolrConfig: solrconfig.xml
Feb 4, 2007 11:28:35 PM org.apache.solr.core.SolrConfig initConfig
INFO: Loaded SolrConfig: solrconfig.xml
Feb 4, 2007 11:28:35 PM org.apache.solr.schema.IndexSchema readConfig
INFO: Reading Solr Schema
Feb 4, 2007 11:28:35 PM org.apache.solr.schema.IndexSchema readConfig
INFO: Schema name=test
Feb 4, 2007 11:28:38 PM org.apache.solr.schema.IndexSchema readConfig
INFO: default search field is text
Feb 4, 2007 11:28:38 PM org.apache.solr.schema.IndexSchema readConfig
INFO: unique key field: id
Feb 4, 2007 11:28:38 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler standard=solr.StandardRequestHandler
Feb 4, 2007 11:28:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler indexinfo=solr.IndexInfoRequestHandler
Feb 4, 2007 11:28:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler dismaxOldStyleDefaults=solr.DisMaxRequestHandler
Feb 4, 2007 11:28:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler dismax=solr.DisMaxRequestHandler
Feb 4, 2007 11:28:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler old=solr.tst.OldRequestHandler
Feb 4, 2007 11:28:39 PM org.apache.solr.tst.OldRequestHandler init
INFO: Unused request handler
arguments:{myparam=1000,ratio=1.4142135,myarr=[1, 2],null=foo}
Feb 4, 2007 11:28:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler oldagain=solr.tst.OldRequestHandler
Feb 4, 2007 11:28:39 PM org.apache.solr.tst.OldRequestHandler init
INFO: Unused request handler
arguments:{lst1={op=sqrt,val=2},lst2={op=log,val=10.0}}
Feb 4, 2007 11:28:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler test=solr.tst.TestRequestHandler
Feb 4, 2007 11:28:39 PM org.apache.solr.tst.TestRequestHandler init
INFO: Unused request handler arguments:{}
Feb 4, 2007 11:28:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler defaults=solr.StandardRequestHandler
Feb 4, 2007 11:28:39 PM org.apache.solr.core.SolrCore <init>
INFO: Opening new SolrCore at solr/,
dataDir=c:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxDocs-1170649715296
Feb 4, 2007 11:28:39 PM org.apache.solr.core.SolrCore parseListener
INFO: Searching for listeners: //listener[@event="firstSearcher"]
Feb 4, 2007 11:28:39 PM org.apache.solr.core.SolrCore parseListener
INFO: Searching for listeners: //listener[@event="newSearcher"]
Feb 4, 2007 11:28:39 PM org.apache.solr.core.SolrCore initIndex
WARNING: Solr index directory
'c:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxDocs-1170649715296\index'
doesn't exist. Creating new index...
Feb 4, 2007 11:28:39 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@2200d5 main
Feb 4, 2007 11:28:39 PM org.apache.solr.core.SolrCore registerSearcher
INFO: Registered new searcher Searcher@2200d5 main
Feb 4, 2007 11:28:39 PM
org.apache.solr.update.DirectUpdateHandler2$CommitTracker <init>
INFO: CommitTracker:
org.apache.solr.update.DirectUpdateHandler2$CommitTracker@bad8a8
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A0} in 94ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A1} in 0ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A2} in 0ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A3} in 0ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A4} in 0ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A5} in 0ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A6} in 15ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A7} in 0ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A8} in 16ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A9} in 0ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A10} in 47ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A11} in 0ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A12} in 0ms
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A13} in 0ms
Feb 4, 2007 11:28:40 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:A1&qt=standard&version=2.2 0 172
Feb 4, 2007 11:28:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A14} in 0ms
Feb 4, 2007 11:28:40 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: start commit(optimize=false,waitFlush=true,waitSearcher=true)
Feb 4, 2007 11:28:40 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 deleting and removing dups for 15 ids
Feb 4, 2007 11:28:41 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:A1&qt=standard&version=2.2 0 0
Feb 4, 2007 11:28:41 PM org.apache.solr.core.SolrCore close
INFO: CLOSING SolrCore!
Feb 4, 2007 11:28:41 PM org.apache.solr.core.SolrCore closeSearcher
INFO: Closing main searcher on request.
Feb 4, 2007 11:28:41 PM org.apache.solr.search.SolrIndexSearcher close
INFO: Closing Searcher@2200d5 main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
queryResultCache{lookups=2,hits=1,hitratio=0.50,inserts=1,evictions=0,size=1,cumulative_lookups=2,cumulative_hits=1,cumulative_hitratio=0.50,cumulative_inserts=1,cumulative_evictions=0}
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 4, 2007 11:28:41 PM org.apache.solr.update.DirectUpdateHandler2 close
INFO: closing DirectUpdateHandler2{commits=1,autocommits=0,optimizes=0,docsPending=0,deletesPending=15,adds=15,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=15,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,docsDeleted=0}
Feb 4, 2007 11:28:41 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@737371 DirectUpdateHandler2
Feb 4, 2007 11:28:41 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 docs deleted=0
Feb 4, 2007 11:28:41 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@149d886 main
Feb 4, 2007 11:28:41 PM org.apache.solr.core.SolrException log
SEVERE: java.util.concurrent.RejectedExecutionException
at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:1477)
at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:384)
at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:856)
at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:45)
at java.util.concurrent.Executors$DelegatedExecutorService.submit(Executors.java:606)
at org.apache.solr.core.SolrCore.getSearcher(SolrCore.java:486)
at org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:514)
at org.apache.solr.update.DirectUpdateHandler2$CommitTracker.run(DirectUpdateHandler2.java:625)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
at java.util.concurrent.FutureTask.run(FutureTask.java:123)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:65)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:168)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
at java.lang.Thread.run(Thread.java:595)
Feb 4, 2007 11:28:41 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: end_commit_flush
Feb 4, 2007 11:28:41 PM org.apache.solr.update.DirectUpdateHandler2 close
INFO: closed DirectUpdateHandler2{commits=1,autocommits=0,optimizes=0,docsPending=0,deletesPending=0,adds=15,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=15,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,docsDeleted=0}
!!!! WARNING: best effort to remove
c:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxDocs-1170649715296
FAILED !!!!!
Feb 4, 2007 11:28:41 PM org.apache.solr.core.SolrConfig initConfig
INFO: Loaded SolrConfig: solrconfig.xml
Feb 4, 2007 11:28:41 PM org.apache.solr.schema.IndexSchema readConfig
INFO: Reading Solr Schema
Feb 4, 2007 11:28:41 PM org.apache.solr.schema.IndexSchema readConfig
INFO: Schema name=test
Feb 4, 2007 11:28:44 PM org.apache.solr.schema.IndexSchema readConfig
INFO: default search field is text
Feb 4, 2007 11:28:44 PM org.apache.solr.schema.IndexSchema readConfig
INFO: unique key field: id
Feb 4, 2007 11:28:44 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler standard=solr.StandardRequestHandler
Feb 4, 2007 11:28:44 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler indexinfo=solr.IndexInfoRequestHandler
Feb 4, 2007 11:28:44 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler dismaxOldStyleDefaults=solr.DisMaxRequestHandler
Feb 4, 2007 11:28:44 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler dismax=solr.DisMaxRequestHandler
Feb 4, 2007 11:28:44 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler old=solr.tst.OldRequestHandler
Feb 4, 2007 11:28:44 PM org.apache.solr.tst.OldRequestHandler init
INFO: Unused request handler
arguments:{myparam=1000,ratio=1.4142135,myarr=[1, 2],null=foo}
Feb 4, 2007 11:28:44 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler oldagain=solr.tst.OldRequestHandler
Feb 4, 2007 11:28:44 PM org.apache.solr.tst.OldRequestHandler init
INFO: Unused request handler
arguments:{lst1={op=sqrt,val=2},lst2={op=log,val=10.0}}
Feb 4, 2007 11:28:44 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler test=solr.tst.TestRequestHandler
Feb 4, 2007 11:28:44 PM org.apache.solr.tst.TestRequestHandler init
INFO: Unused request handler arguments:{}
Feb 4, 2007 11:28:44 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler defaults=solr.StandardRequestHandler
Feb 4, 2007 11:28:44 PM org.apache.solr.core.SolrCore <init>
INFO: Opening new SolrCore at solr/,
dataDir=c:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxTime-1170649721796
Feb 4, 2007 11:28:44 PM org.apache.solr.core.SolrCore parseListener
INFO: Searching for listeners: //listener[@event="firstSearcher"]
Feb 4, 2007 11:28:44 PM org.apache.solr.core.SolrCore parseListener
INFO: Searching for listeners: //listener[@event="newSearcher"]
Feb 4, 2007 11:28:44 PM org.apache.solr.core.SolrCore initIndex
WARNING: Solr index directory
'c:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxTime-1170649721796\index'
doesn't exist. Creating new index...
Feb 4, 2007 11:28:44 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@17ba38f main
Feb 4, 2007 11:28:44 PM org.apache.solr.core.SolrCore registerSearcher
INFO: Registered new searcher Searcher@17ba38f main
Feb 4, 2007 11:28:44 PM
org.apache.solr.update.DirectUpdateHandler2$CommitTracker <init>
INFO: CommitTracker:
org.apache.solr.update.DirectUpdateHandler2$CommitTracker@1f1235b
Feb 4, 2007 11:28:44 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={529} in 78ms
Feb 4, 2007 11:28:44 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:529&qt=standard&version=2.2 0 0
Feb 4, 2007 11:28:45 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: start commit(optimize=false,waitFlush=true,waitSearcher=true)
Feb 4, 2007 11:28:45 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 deleting and removing dups for 1 ids
Feb 4, 2007 11:28:45 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:529&qt=standard&version=2.2 0 0
Feb 4, 2007 11:28:45 PM org.apache.solr.core.SolrCore close
INFO: CLOSING SolrCore!
Feb 4, 2007 11:28:45 PM org.apache.solr.core.SolrCore closeSearcher
INFO: Closing main searcher on request.
Feb 4, 2007 11:28:45 PM org.apache.solr.search.SolrIndexSearcher close
INFO: Closing Searcher@17ba38f main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
queryResultCache{lookups=2,hits=1,hitratio=0.50,inserts=1,evictions=0,size=1,cumulative_lookups=4,cumulative_hits=2,cumulative_hitratio=0.50,cumulative_inserts=2,cumulative_evictions=0}
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 4, 2007 11:28:45 PM org.apache.solr.update.DirectUpdateHandler2 close
INFO: closing DirectUpdateHandler2{commits=1,autocommits=0,optimizes=0,docsPending=0,deletesPending=1,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,docsDeleted=0}
Feb 4, 2007 11:28:45 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@14d7745 DirectUpdateHandler2
Feb 4, 2007 11:28:45 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 docs deleted=0
Feb 4, 2007 11:28:45 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@d17ec3 main
Feb 4, 2007 11:28:45 PM org.apache.solr.core.SolrException log
SEVERE: java.util.concurrent.RejectedExecutionException
at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:1477)
at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:384)
at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:856)
at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:45)
at java.util.concurrent.Executors$DelegatedExecutorService.submit(Executors.java:606)
at org.apache.solr.core.SolrCore.getSearcher(SolrCore.java:486)
at org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:514)
at org.apache.solr.update.DirectUpdateHandler2$CommitTracker.run(DirectUpdateHandler2.java:625)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
at java.util.concurrent.FutureTask.run(FutureTask.java:123)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:65)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:168)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
at java.lang.Thread.run(Thread.java:595)
Feb 4, 2007 11:28:45 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: end_commit_flush
Feb 4, 2007 11:28:45 PM org.apache.solr.update.DirectUpdateHandler2 close
INFO: closed DirectUpdateHandler2{commits=1,autocommits=1,optimizes=0,docsPending=0,deletesPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,docsDeleted=0}
!!!! WARNING: best effort to remove
c:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxTime-1170649721796
FAILED !!!!!
]]></system-err>
</testsuite>
-Yonik
Re: AutoCommitTest failing
Posted by Yonik Seeley <yo...@apache.org>.
OK, the wildcard seems to be norton antivirus.
Antivirus on:
BUILD FAILED
Total time: 2 minutes 22 seconds
Antivirus off:
BUILD SUCCESSFUL
Total time: 1 minute 24 seconds
Not yet sure why that would make the difference... and only on this test.
-Yonik
Re: AutoCommitTest failing
Posted by Yonik Seeley <yo...@apache.org>.
Removing the directory did not help.
I just tried a fresh checkout on my C: drive, still no dice.
I'll try a reboot, and a remote login to my machine at work next.
-Yonik
<?xml version="1.0" encoding="UTF-8" ?>
<testsuite errors="0" failures="1"
name="org.apache.solr.update.AutoCommitTest" tests="2" time="5.313">
<properties>
<property name="java.vendor" value="Sun Microsystems Inc."></property>
<property name="sun.management.compiler" value="HotSpot Client
Compiler"></property>
<property name="dist" value="dist"></property>
<property name="lib" value="lib"></property>
<property name="os.name" value="Windows XP"></property>
<property name="sun.boot.class.path"
value="C:\opt\jdk15\jre\lib\rt.jar;C:\opt\jdk15\jre\lib\i18n.jar;C:\opt\jdk15\jre\lib\sunrsasign.jar;C:\opt\jdk15\jre\lib\jsse.jar;C:\opt\jdk15\jre\lib\jce.jar;C:\opt\jdk15\jre\lib\charsets.jar;C:\opt\jdk15\jre\classes"></property>
<property name="fullnamever" value="apache-solr-1.2-dev"></property>
<property name="TODAY" value="February 4 2007"></property>
<property name="sun.desktop" value="windows"></property>
<property name="java.vm.specification.vendor" value="Sun
Microsystems Inc."></property>
<property name="ant.home" value="c:/opt/java/ant"></property>
<property name="java.runtime.version" value="1.5.0_09-b01"></property>
<property name="user.name" value="Yonik"></property>
<property name="dest" value="build"></property>
<property name="user.language" value="en"></property>
<property name="build.javadoc" value="build/docs/api"></property>
<property name="sun.boot.library.path"
value="C:\opt\jdk15\jre\bin"></property>
<property name="ant.project.name" value="solr"></property>
<property name="DSTAMP" value="2007-02-04"></property>
<property name="java.version" value="1.5.0_09"></property>
<property name="version" value="1.2-dev"></property>
<property name="user.timezone" value=""></property>
<property name="sun.arch.data.model" value="32"></property>
<property name="java.endorsed.dirs"
value="C:\opt\jdk15\jre\lib\endorsed"></property>
<property name="sun.cpu.isalist" value=""></property>
<property name="sun.jnu.encoding" value="Cp1252"></property>
<property name="file.encoding.pkg" value="sun.io"></property>
<property name="file.separator" value="\"></property>
<property name="java.specification.name" value="Java Platform API
Specification"></property>
<property name="javadoc.packages" value="org.apache.solr.*"></property>
<property name="java.class.version" value="49.0"></property>
<property name="src" value="src"></property>
<property name="user.country" value="US"></property>
<property name="java.home" value="C:\opt\jdk15\jre"></property>
<property name="java.vm.info" value="mixed mode"></property>
<property name="ant.file" value="c:\tmp555\solr\build.xml"></property>
<property name="os.version" value="5.1"></property>
<property name="javadoc.access" value="protected"></property>
<property name="path.separator" value=";"></property>
<property name="specversion" value="1.1.2007.02.04.22.29.04"></property>
<property name="java.vm.version" value="1.5.0_09-b01"></property>
<property name="java.compat.version" value="1.5"></property>
<property name="user.variant" value=""></property>
<property name="ant.library.dir" value="c:/opt/java/ant/lib"></property>
<property name="junit.output.dir"
value="C:\tmp555\solr\build\test-results"></property>
<property name="java.awt.printerjob"
value="sun.awt.windows.WPrinterJob"></property>
<property name="sun.io.unicode.encoding" value="UnicodeLittle"></property>
<property name="awt.toolkit" value="sun.awt.windows.WToolkit"></property>
<property name="javadoc.link.lucene"
value="http://lucene.apache.org/java/docs/api/"></property>
<property name="user.home" value="C:\Documents and
Settings\Yonik"></property>
<property name="ant.file.solr" value="C:\tmp555\solr\build.xml"></property>
<property name="java.specification.vendor" value="Sun Microsystems
Inc."></property>
<property name="java.library.path"
value="C:\opt\jdk15\jre\bin;.;C:\WINDOWS\system32;C:\WINDOWS;c:\opt\vcpp\bin;c:\opt\jdk15\bin;C:\cygwin\usr\local\bin;C:\cygwin\bin;C:\cygwin\bin;C:\cygwin\usr\X11R6\bin;c:\opt\ruby\bin;c:\WINDOWS\system32;c:\WINDOWS;c:\WINDOWS\System32\Wbem;c:\bin;c:\opt\python;c:\opt\vim\vim63;c:\Program
Files\ATI Technologies\ATI Control Panel;c:\Program
Files\QuickTime\QTSystem\;c:\opt\mysql\bin;c:\opt\java\ant\bin;c:\opt\ruby\bin;c:\opt\maven\bin"></property>
<property name="java.vendor.url" value="http://java.sun.com/"></property>
<property name="checksum.algorithm" value="md5"></property>
<property name="java.vm.vendor" value="Sun Microsystems Inc."></property>
<property name="java.runtime.name" value="Java(TM) 2 Runtime
Environment, Standard Edition"></property>
<property name="java.class.path"
value="C:\tmp555\solr\lib\commons-fileupload-20070107.jar;C:\tmp555\solr\lib\commons-io-1.2.jar;C:\tmp555\solr\lib\lucene-core-nightly.jar;C:\tmp555\solr\lib\lucene-highlighter-nightly.jar;C:\tmp555\solr\lib\lucene-snowball-nightly.jar;C:\tmp555\solr\lib\servlet-api-2.4.jar;C:\tmp555\solr\lib\xpp3-1.1.3.4.O.jar;C:\tmp555\solr\build;C:\tmp555\solr\build\tests;C:\tmp555\solr;C:\opt\java\ant\lib\junit.jar;C:\opt\java\ant\lib\ant-launcher.jar;C:\opt\java\ant\lib\ant.jar;C:\opt\java\ant\lib\ant-junit.jar"></property>
<property name="year" value="2007"></property>
<property name="ant.version" value="Apache Ant version 1.6.5
compiled on June 2 2005"></property>
<property name="example" value="example"></property>
<property name="dateversion" value="2007.02.04.22.29.04"></property>
<property name="TSTAMP" value="22:29:04"></property>
<property name="junit.includes"
value="**/Test*.java,**/*Test.java"></property>
<property name="java.vm.specification.name" value="Java Virtual
Machine Specification"></property>
<property name="java.vm.specification.version" value="1.0"></property>
<property name="sun.cpu.endian" value="little"></property>
<property name="sun.os.patch.level" value="Service Pack 2"></property>
<property name="java.io.tmpdir"
value="c:\DOCUME~1\Yonik\LOCALS~1\Temp\"></property>
<property name="cygwin.user.home" value="c:/yonik"></property>
<property name="java.vendor.url.bug"
value="http://java.sun.com/cgi-bin/bugreport.cgi"></property>
<property name="Name" value="Solr"></property>
<property name="javadoc.link.junit"
value="http://junit.sourceforge.net/javadoc/"></property>
<property name="junit.reports"
value="C:\tmp555\solr\build\test-results\reports"></property>
<property name="javadoc.years" value="2006 - 2007"></property>
<property name="java.awt.graphicsenv"
value="sun.awt.Win32GraphicsEnvironment"></property>
<property name="os.arch" value="x86"></property>
<property name="java.ext.dirs" value="C:\opt\jdk15\jre\lib\ext"></property>
<property name="user.dir"
value="C:\tmp555\solr\src\test\test-files"></property>
<property name="build.docs" value="build/docs"></property>
<property name="javadoc.link.java"
value="http://java.sun.com/j2se/1.5.0/docs/api/"></property>
<property name="fullname" value="apache-solr"></property>
<property name="line.separator" value="
"></property>
<property name="java.vm.name" value="Java HotSpot(TM) Client VM"></property>
<property name="basedir" value="C:\tmp555\solr"></property>
<property name="ant.java.version" value="1.5"></property>
<property name="file.encoding" value="Cp1252"></property>
<property name="junitPresent" value="true"></property>
<property name="java.specification.version" value="1.5"></property>
</properties>
<testcase classname="org.apache.solr.update.AutoCommitTest"
name="testMaxDocs" time="2.36">
<failure message="should find one query failed XPath:
//result[@numFound=1] xml response was: <?xml
version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int
name="status">0</int><int
name="QTime">0</int></lst><result
name="response" numFound="0"
start="0"/>
</response>
" type="junit.framework.AssertionFailedError">junit.framework.AssertionFailedError:
should find one query failed XPath: //result[@numFound=1] xml response
was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int
name="status">0</int><int
name="QTime">0</int></lst><result
name="response" numFound="0"
start="0"/>
</response>
at org.apache.solr.util.AbstractSolrTestCase.assertQ(AbstractSolrTestCase.java:157)
at org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:98)
</failure>
</testcase>
<testcase classname="org.apache.solr.update.AutoCommitTest"
name="testMaxTime" time="2.922"></testcase>
<system-out><![CDATA[]]></system-out>
<system-err><![CDATA[Feb 4, 2007 10:31:00 PM
org.apache.solr.core.Config getInstanceDir
INFO: Solr home defaulted to 'solr/' (system property solr.solr.home not set)
Feb 4, 2007 10:31:00 PM org.apache.solr.core.SolrConfig initConfig
INFO: Loaded SolrConfig: solrconfig.xml
Feb 4, 2007 10:31:00 PM org.apache.solr.core.SolrConfig initConfig
INFO: Loaded SolrConfig: solrconfig.xml
Feb 4, 2007 10:31:00 PM org.apache.solr.schema.IndexSchema readConfig
INFO: Reading Solr Schema
Feb 4, 2007 10:31:01 PM org.apache.solr.schema.IndexSchema readConfig
INFO: Schema name=test
Feb 4, 2007 10:31:01 PM org.apache.solr.schema.IndexSchema readConfig
INFO: default search field is text
Feb 4, 2007 10:31:01 PM org.apache.solr.schema.IndexSchema readConfig
INFO: unique key field: id
Feb 4, 2007 10:31:01 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler standard=solr.StandardRequestHandler
Feb 4, 2007 10:31:01 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler indexinfo=solr.IndexInfoRequestHandler
Feb 4, 2007 10:31:01 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler dismaxOldStyleDefaults=solr.DisMaxRequestHandler
Feb 4, 2007 10:31:01 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler dismax=solr.DisMaxRequestHandler
Feb 4, 2007 10:31:01 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler old=solr.tst.OldRequestHandler
Feb 4, 2007 10:31:01 PM org.apache.solr.tst.OldRequestHandler init
INFO: Unused request handler
arguments:{myparam=1000,ratio=1.4142135,myarr=[1, 2],null=foo}
Feb 4, 2007 10:31:02 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler oldagain=solr.tst.OldRequestHandler
Feb 4, 2007 10:31:02 PM org.apache.solr.tst.OldRequestHandler init
INFO: Unused request handler
arguments:{lst1={op=sqrt,val=2},lst2={op=log,val=10.0}}
Feb 4, 2007 10:31:02 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler test=solr.tst.TestRequestHandler
Feb 4, 2007 10:31:02 PM org.apache.solr.tst.TestRequestHandler init
INFO: Unused request handler arguments:{}
Feb 4, 2007 10:31:02 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler defaults=solr.StandardRequestHandler
Feb 4, 2007 10:31:02 PM org.apache.solr.core.SolrCore <init>
INFO: Opening new SolrCore at solr/,
dataDir=c:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxDocs-1170646260718
Feb 4, 2007 10:31:02 PM org.apache.solr.core.SolrCore parseListener
INFO: Searching for listeners: //listener[@event="firstSearcher"]
Feb 4, 2007 10:31:02 PM org.apache.solr.core.SolrCore parseListener
INFO: Searching for listeners: //listener[@event="newSearcher"]
Feb 4, 2007 10:31:02 PM org.apache.solr.core.SolrCore initIndex
WARNING: Solr index directory
'c:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxDocs-1170646260718\index'
doesn't exist. Creating new index...
Feb 4, 2007 10:31:02 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@1a42792 main
Feb 4, 2007 10:31:02 PM org.apache.solr.core.SolrCore registerSearcher
INFO: Registered new searcher Searcher@1a42792 main
Feb 4, 2007 10:31:02 PM
org.apache.solr.update.DirectUpdateHandler2$CommitTracker <init>
INFO: CommitTracker:
org.apache.solr.update.DirectUpdateHandler2$CommitTracker@5d9084
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A0} in 62ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A1} in 0ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A2} in 0ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A3} in 0ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A4} in 16ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A5} in 0ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A6} in 0ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A7} in 0ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A8} in 0ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A9} in 15ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A10} in 32ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A11} in 0ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A12} in 0ms
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A13} in 0ms
Feb 4, 2007 10:31:02 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:A1&qt=standard&version=2.2 0 62
Feb 4, 2007 10:31:02 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A14} in 0ms
Feb 4, 2007 10:31:02 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: start commit(optimize=false,waitFlush=true,waitSearcher=true)
Feb 4, 2007 10:31:02 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 deleting and removing dups for 15 ids
Feb 4, 2007 10:31:03 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:A1&qt=standard&version=2.2 0 0
Feb 4, 2007 10:31:03 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@e61a35 DirectUpdateHandler2
Feb 4, 2007 10:31:03 PM org.apache.solr.core.SolrCore close
INFO: CLOSING SolrCore!
Feb 4, 2007 10:31:03 PM org.apache.solr.core.SolrCore closeSearcher
INFO: Closing main searcher on request.
Feb 4, 2007 10:31:03 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 docs deleted=0
Feb 4, 2007 10:31:03 PM org.apache.solr.search.SolrIndexSearcher close
INFO: Closing Searcher@1a42792 main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
queryResultCache{lookups=2,hits=1,hitratio=0.50,inserts=1,evictions=0,size=1,cumulative_lookups=2,cumulative_hits=1,cumulative_hitratio=0.50,cumulative_inserts=1,cumulative_evictions=0}
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 4, 2007 10:31:03 PM org.apache.solr.update.DirectUpdateHandler2 close
INFO: closing DirectUpdateHandler2{commits=1,autocommits=0,optimizes=0,docsPending=0,deletesPending=0,adds=15,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=15,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,docsDeleted=0}
Feb 4, 2007 10:31:03 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@90c06f main
Feb 4, 2007 10:31:03 PM org.apache.solr.core.SolrException log
SEVERE: java.util.concurrent.RejectedExecutionException
at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:1477)
at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:384)
at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:856)
at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:45)
at java.util.concurrent.Executors$DelegatedExecutorService.submit(Executors.java:606)
at org.apache.solr.core.SolrCore.getSearcher(SolrCore.java:486)
at org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:514)
at org.apache.solr.update.DirectUpdateHandler2$CommitTracker.run(DirectUpdateHandler2.java:625)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
at java.util.concurrent.FutureTask.run(FutureTask.java:123)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:65)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:168)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
at java.lang.Thread.run(Thread.java:595)
Feb 4, 2007 10:31:03 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: end_commit_flush
Feb 4, 2007 10:31:03 PM org.apache.solr.update.DirectUpdateHandler2 close
INFO: closed DirectUpdateHandler2{commits=1,autocommits=1,optimizes=0,docsPending=0,deletesPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=15,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,docsDeleted=0}
!!!! WARNING: best effort to remove
c:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxDocs-1170646260718
FAILED !!!!!
Feb 4, 2007 10:31:03 PM org.apache.solr.core.SolrConfig initConfig
INFO: Loaded SolrConfig: solrconfig.xml
Feb 4, 2007 10:31:03 PM org.apache.solr.schema.IndexSchema readConfig
INFO: Reading Solr Schema
Feb 4, 2007 10:31:03 PM org.apache.solr.schema.IndexSchema readConfig
INFO: Schema name=test
Feb 4, 2007 10:31:03 PM org.apache.solr.schema.IndexSchema readConfig
INFO: default search field is text
Feb 4, 2007 10:31:03 PM org.apache.solr.schema.IndexSchema readConfig
INFO: unique key field: id
Feb 4, 2007 10:31:03 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler standard=solr.StandardRequestHandler
Feb 4, 2007 10:31:03 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler indexinfo=solr.IndexInfoRequestHandler
Feb 4, 2007 10:31:03 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler dismaxOldStyleDefaults=solr.DisMaxRequestHandler
Feb 4, 2007 10:31:03 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler dismax=solr.DisMaxRequestHandler
Feb 4, 2007 10:31:03 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler old=solr.tst.OldRequestHandler
Feb 4, 2007 10:31:03 PM org.apache.solr.tst.OldRequestHandler init
INFO: Unused request handler
arguments:{myparam=1000,ratio=1.4142135,myarr=[1, 2],null=foo}
Feb 4, 2007 10:31:03 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler oldagain=solr.tst.OldRequestHandler
Feb 4, 2007 10:31:03 PM org.apache.solr.tst.OldRequestHandler init
INFO: Unused request handler
arguments:{lst1={op=sqrt,val=2},lst2={op=log,val=10.0}}
Feb 4, 2007 10:31:03 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler test=solr.tst.TestRequestHandler
Feb 4, 2007 10:31:03 PM org.apache.solr.tst.TestRequestHandler init
INFO: Unused request handler arguments:{}
Feb 4, 2007 10:31:03 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler defaults=solr.StandardRequestHandler
Feb 4, 2007 10:31:03 PM org.apache.solr.core.SolrCore <init>
INFO: Opening new SolrCore at solr/,
dataDir=c:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxTime-1170646263078
Feb 4, 2007 10:31:03 PM org.apache.solr.core.SolrCore parseListener
INFO: Searching for listeners: //listener[@event="firstSearcher"]
Feb 4, 2007 10:31:03 PM org.apache.solr.core.SolrCore parseListener
INFO: Searching for listeners: //listener[@event="newSearcher"]
Feb 4, 2007 10:31:03 PM org.apache.solr.core.SolrCore initIndex
WARNING: Solr index directory
'c:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxTime-1170646263078\index'
doesn't exist. Creating new index...
Feb 4, 2007 10:31:03 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@a9255c main
Feb 4, 2007 10:31:03 PM org.apache.solr.core.SolrCore registerSearcher
INFO: Registered new searcher Searcher@a9255c main
Feb 4, 2007 10:31:03 PM
org.apache.solr.update.DirectUpdateHandler2$CommitTracker <init>
INFO: CommitTracker:
org.apache.solr.update.DirectUpdateHandler2$CommitTracker@14d7745
Feb 4, 2007 10:31:03 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={529} in 16ms
Feb 4, 2007 10:31:03 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:529&qt=standard&version=2.2 0 0
Feb 4, 2007 10:31:04 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: start commit(optimize=false,waitFlush=true,waitSearcher=true)
Feb 4, 2007 10:31:04 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 deleting and removing dups for 1 ids
Feb 4, 2007 10:31:04 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@193f6e2 DirectUpdateHandler2
Feb 4, 2007 10:31:04 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 docs deleted=0
Feb 4, 2007 10:31:04 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@1358f03 main
Feb 4, 2007 10:31:04 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: end_commit_flush
Feb 4, 2007 10:31:04 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@1358f03 main from Searcher@a9255c main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 4, 2007 10:31:04 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@1358f03 main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 4, 2007 10:31:04 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@1358f03 main from Searcher@a9255c main
queryResultCache{lookups=1,hits=0,hitratio=0.00,inserts=1,evictions=0,size=1,cumulative_lookups=3,cumulative_hits=1,cumulative_hitratio=0.33,cumulative_inserts=2,cumulative_evictions=0}
Feb 4, 2007 10:31:04 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@1358f03 main
queryResultCache{lookups=0,hits=0,hitratio=0.00,inserts=1,evictions=0,size=1,cumulative_lookups=3,cumulative_hits=1,cumulative_hitratio=0.33,cumulative_inserts=2,cumulative_evictions=0}
Feb 4, 2007 10:31:04 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@1358f03 main from Searcher@a9255c main
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 4, 2007 10:31:04 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@1358f03 main
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 4, 2007 10:31:04 PM org.apache.solr.core.SolrCore registerSearcher
INFO: Registered new searcher Searcher@1358f03 main
Feb 4, 2007 10:31:04 PM org.apache.solr.search.SolrIndexSearcher close
INFO: Closing Searcher@a9255c main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
queryResultCache{lookups=1,hits=0,hitratio=0.00,inserts=1,evictions=0,size=1,cumulative_lookups=3,cumulative_hits=1,cumulative_hitratio=0.33,cumulative_inserts=2,cumulative_evictions=0}
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 4, 2007 10:31:04 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:529&qt=standard&version=2.2 0 0
Feb 4, 2007 10:31:04 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={500} in 16ms
Feb 4, 2007 10:31:04 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={500} in 15ms
Feb 4, 2007 10:31:04 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={500} in 0ms
Feb 4, 2007 10:31:04 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={500} in 0ms
Feb 4, 2007 10:31:04 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={500} in 0ms
Feb 4, 2007 10:31:04 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={500} in 0ms
Feb 4, 2007 10:31:04 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={500} in 0ms
Feb 4, 2007 10:31:04 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={500} in 0ms
Feb 4, 2007 10:31:04 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={500} in 0ms
Feb 4, 2007 10:31:04 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={500} in 0ms
Feb 4, 2007 10:31:04 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:500&qt=standard&version=2.2 0 0
Feb 4, 2007 10:31:05 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: start commit(optimize=false,waitFlush=true,waitSearcher=true)
Feb 4, 2007 10:31:05 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 deleting and removing dups for 1 ids
Feb 4, 2007 10:31:05 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@9cfec1 DirectUpdateHandler2
Feb 4, 2007 10:31:05 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 docs deleted=9
Feb 4, 2007 10:31:05 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@6782a9 main
Feb 4, 2007 10:31:05 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: end_commit_flush
Feb 4, 2007 10:31:05 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@6782a9 main from Searcher@1358f03 main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 4, 2007 10:31:05 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@6782a9 main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 4, 2007 10:31:05 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@6782a9 main from Searcher@1358f03 main
queryResultCache{lookups=2,hits=1,hitratio=0.50,inserts=2,evictions=0,size=2,cumulative_lookups=5,cumulative_hits=2,cumulative_hitratio=0.40,cumulative_inserts=3,cumulative_evictions=0}
Feb 4, 2007 10:31:05 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@6782a9 main
queryResultCache{lookups=0,hits=0,hitratio=0.00,inserts=2,evictions=0,size=2,cumulative_lookups=5,cumulative_hits=2,cumulative_hitratio=0.40,cumulative_inserts=3,cumulative_evictions=0}
Feb 4, 2007 10:31:05 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming Searcher@6782a9 main from Searcher@1358f03 main
documentCache{lookups=2,hits=1,hitratio=0.50,inserts=1,evictions=0,size=1,cumulative_lookups=2,cumulative_hits=1,cumulative_hitratio=0.50,cumulative_inserts=1,cumulative_evictions=0}
Feb 4, 2007 10:31:05 PM org.apache.solr.search.SolrIndexSearcher warm
INFO: autowarming result for Searcher@6782a9 main
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=2,cumulative_hits=1,cumulative_hitratio=0.50,cumulative_inserts=1,cumulative_evictions=0}
Feb 4, 2007 10:31:05 PM org.apache.solr.core.SolrCore registerSearcher
INFO: Registered new searcher Searcher@6782a9 main
Feb 4, 2007 10:31:05 PM org.apache.solr.search.SolrIndexSearcher close
INFO: Closing Searcher@1358f03 main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
queryResultCache{lookups=2,hits=1,hitratio=0.50,inserts=2,evictions=0,size=2,cumulative_lookups=5,cumulative_hits=2,cumulative_hitratio=0.40,cumulative_inserts=3,cumulative_evictions=0}
documentCache{lookups=2,hits=1,hitratio=0.50,inserts=1,evictions=0,size=1,cumulative_lookups=2,cumulative_hits=1,cumulative_hitratio=0.50,cumulative_inserts=1,cumulative_evictions=0}
Feb 4, 2007 10:31:05 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:500&qt=standard&version=2.2 0 0
Feb 4, 2007 10:31:05 PM org.apache.solr.core.SolrCore close
INFO: CLOSING SolrCore!
Feb 4, 2007 10:31:05 PM org.apache.solr.core.SolrCore closeSearcher
INFO: Closing main searcher on request.
Feb 4, 2007 10:31:05 PM org.apache.solr.search.SolrIndexSearcher close
INFO: Closing Searcher@6782a9 main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
queryResultCache{lookups=1,hits=1,hitratio=1.00,inserts=2,evictions=0,size=2,cumulative_lookups=6,cumulative_hits=3,cumulative_hitratio=0.50,cumulative_inserts=3,cumulative_evictions=0}
documentCache{lookups=2,hits=1,hitratio=0.50,inserts=1,evictions=0,size=1,cumulative_lookups=4,cumulative_hits=2,cumulative_hitratio=0.50,cumulative_inserts=2,cumulative_evictions=0}
Feb 4, 2007 10:31:05 PM org.apache.solr.update.DirectUpdateHandler2 close
INFO: closing DirectUpdateHandler2{commits=2,autocommits=2,optimizes=0,docsPending=0,deletesPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=11,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,docsDeleted=9}
Feb 4, 2007 10:31:05 PM org.apache.solr.update.DirectUpdateHandler2 close
INFO: closed DirectUpdateHandler2{commits=2,autocommits=2,optimizes=0,docsPending=0,deletesPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=11,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,docsDeleted=9}
]]></system-err>
</testsuite>
Re: AutoCommitTest failing
Posted by Erik Hatcher <er...@ehatchersolutions.com>.
On Feb 4, 2007, at 8:34 PM, Yonik Seeley wrote:
> On 2/4/07, Ryan McKinley <ry...@gmail.com> wrote:
>> is this on your machine or on the apache machine? I have never had a
>> problem running on my machine.
>
> My local machine.... happens every time now.
> I think Erik said it was failing for him also.
Currently all is well (svn revision 503532):
BUILD SUCCESSFUL
Total time: 1 minute 16 seconds
I ran it twice for good measure, and passed both times.
Erik
Re: AutoCommitTest failing
Posted by Ryan McKinley <ry...@gmail.com>.
still trying (in vain) to make this fail on my setup...
The one line that looks suspicious from your stack trace is:
!!!! WARNING: best effort to remove
C:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxDocs-1170627577515
FAILED !!!!!
can you delete the directory where solr stores the testing index manually?
Re: AutoCommitTest failing
Posted by Yonik Seeley <yo...@apache.org>.
On 2/4/07, Ryan McKinley <ry...@gmail.com> wrote:
> Can you tell specifically what part triggers the bad behavior? or is
> it whenever autocommit gets triggered?
>
> I wish I could be more useful, but from my system (win XP,
> jdk1.5.0_07) things look ok...
I'm on WinXP, and it fails on both Java5 (_09), and Java6.
I'm running off a laptop drive connected via firewire though... so
that might slow it down compared to what you are running.
Here's all the output from when I run it in my IDE (fails there too):
Feb 4, 2007 5:19:37 PM org.apache.solr.core.Config getInstanceDir
INFO: Solr home defaulted to 'solr/' (system property solr.solr.home not set)
Feb 4, 2007 5:19:37 PM org.apache.solr.core.SolrConfig initConfig
INFO: Loaded SolrConfig: solrconfig.xml
Feb 4, 2007 5:19:37 PM org.apache.solr.core.SolrConfig initConfig
INFO: Loaded SolrConfig: solrconfig.xml
Feb 4, 2007 5:19:38 PM org.apache.solr.schema.IndexSchema readConfig
INFO: Reading Solr Schema
Feb 4, 2007 5:19:38 PM org.apache.solr.schema.IndexSchema readConfig
INFO: Schema name=test
Feb 4, 2007 5:19:39 PM org.apache.solr.schema.IndexSchema readConfig
INFO: default search field is text
Feb 4, 2007 5:19:39 PM org.apache.solr.schema.IndexSchema readConfig
INFO: unique key field: id
Feb 4, 2007 5:19:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler standard=solr.StandardRequestHandler
Feb 4, 2007 5:19:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler indexinfo=solr.IndexInfoRequestHandler
Feb 4, 2007 5:19:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler dismaxOldStyleDefaults=solr.DisMaxRequestHandler
Feb 4, 2007 5:19:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler dismax=solr.DisMaxRequestHandler
Feb 4, 2007 5:19:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler old=solr.tst.OldRequestHandler
Feb 4, 2007 5:19:39 PM org.apache.solr.tst.OldRequestHandler init
INFO: Unused request handler
arguments:{myparam=1000,ratio=1.4142135,myarr=[1, 2],null=foo}
Feb 4, 2007 5:19:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler oldagain=solr.tst.OldRequestHandler
Feb 4, 2007 5:19:39 PM org.apache.solr.tst.OldRequestHandler init
INFO: Unused request handler
arguments:{lst1={op=sqrt,val=2},lst2={op=log,val=10.0}}
Feb 4, 2007 5:19:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler test=solr.tst.TestRequestHandler
Feb 4, 2007 5:19:39 PM org.apache.solr.tst.TestRequestHandler init
INFO: Unused request handler arguments:{}
Feb 4, 2007 5:19:39 PM org.apache.solr.core.RequestHandlers <init>
INFO: adding requestHandler defaults=solr.StandardRequestHandler
Feb 4, 2007 5:19:39 PM org.apache.solr.core.SolrCore <init>
INFO: Opening new SolrCore at solr/,
dataDir=C:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxDocs-1170627577515
Feb 4, 2007 5:19:39 PM org.apache.solr.core.SolrCore parseListener
INFO: Searching for listeners: //listener[@event="firstSearcher"]
Feb 4, 2007 5:19:39 PM org.apache.solr.core.SolrCore parseListener
INFO: Searching for listeners: //listener[@event="newSearcher"]
Feb 4, 2007 5:19:39 PM org.apache.solr.core.SolrCore initIndex
WARNING: Solr index directory
'C:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxDocs-1170627577515\index'
doesn't exist. Creating new index...
Feb 4, 2007 5:19:39 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@766a24 main
Feb 4, 2007 5:19:39 PM org.apache.solr.core.SolrCore registerSearcher
INFO: Registered new searcher Searcher@766a24 main
Feb 4, 2007 5:19:39 PM
org.apache.solr.update.DirectUpdateHandler2$CommitTracker <init>
INFO: CommitTracker:
org.apache.solr.update.DirectUpdateHandler2$CommitTracker@154864a
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A0} in 234ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A1} in 0ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A2} in 0ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A3} in 0ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A4} in 16ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A5} in 0ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A6} in 0ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A7} in 0ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A8} in 16ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A9} in 0ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A10} in 31ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A11} in 0ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A12} in 16ms
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A13} in 0ms
Feb 4, 2007 5:19:40 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:A1&qt=standard&version=2.2 0 296
Feb 4, 2007 5:19:40 PM org.apache.solr.handler.XmlUpdateRequestHandler update
INFO: added id={A14} in 0ms
Feb 4, 2007 5:19:40 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: start commit(optimize=false,waitFlush=true,waitSearcher=true)
Feb 4, 2007 5:19:40 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 deleting and removing dups for 15 ids
Feb 4, 2007 5:19:41 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@1fd5e2 DirectUpdateHandler2
Feb 4, 2007 5:19:41 PM org.apache.solr.core.SolrCore execute
INFO: rows=20&start=0&q=id:A1&qt=standard&version=2.2 0 0
Feb 4, 2007 5:19:41 PM org.apache.solr.update.DirectUpdateHandler2 doDeletions
INFO: DirectUpdateHandler2 docs deleted=0
Feb 4, 2007 5:19:41 PM org.apache.solr.core.SolrCore close
INFO: CLOSING SolrCore!
Feb 4, 2007 5:19:41 PM org.apache.solr.core.SolrCore closeSearcher
INFO: Closing main searcher on request.
Feb 4, 2007 5:19:41 PM org.apache.solr.search.SolrIndexSearcher close
INFO: Closing Searcher@766a24 main
filterCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
queryResultCache{lookups=2,hits=1,hitratio=0.50,inserts=1,evictions=0,size=1,cumulative_lookups=2,cumulative_hits=1,cumulative_hitratio=0.50,cumulative_inserts=1,cumulative_evictions=0}
documentCache{lookups=0,hits=0,hitratio=0.00,inserts=0,evictions=0,size=0,cumulative_lookups=0,cumulative_hits=0,cumulative_hitratio=0.00,cumulative_inserts=0,cumulative_evictions=0}
Feb 4, 2007 5:19:41 PM org.apache.solr.update.DirectUpdateHandler2 close
INFO: closing DirectUpdateHandler2{commits=1,autocommits=0,optimizes=0,docsPending=0,deletesPending=0,adds=15,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=15,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,docsDeleted=0}
Feb 4, 2007 5:19:41 PM org.apache.solr.search.SolrIndexSearcher <init>
INFO: Opening Searcher@1c6572b main
Feb 4, 2007 5:19:41 PM org.apache.solr.core.SolrException log
SEVERE: java.util.concurrent.RejectedExecutionException
at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:1477)
at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:384)
at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:856)
at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:45)
at java.util.concurrent.Executors$DelegatedExecutorService.submit(Executors.java:606)
at org.apache.solr.core.SolrCore.getSearcher(SolrCore.java:486)
at org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:514)
at org.apache.solr.update.DirectUpdateHandler2$CommitTracker.run(DirectUpdateHandler2.java:625)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
at java.util.concurrent.FutureTask.run(FutureTask.java:123)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:65)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:168)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
at java.lang.Thread.run(Thread.java:595)
Feb 4, 2007 5:19:41 PM org.apache.solr.update.DirectUpdateHandler2 commit
INFO: end_commit_flush
Feb 4, 2007 5:19:41 PM org.apache.solr.update.DirectUpdateHandler2 close
INFO: closed DirectUpdateHandler2{commits=1,autocommits=1,optimizes=0,docsPending=0,deletesPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=15,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,docsDeleted=0}
!!!! WARNING: best effort to remove
C:\DOCUME~1\Yonik\LOCALS~1\Temp\org.apache.solr.update.AutoCommitTest-testMaxDocs-1170627577515
FAILED !!!!!
junit.framework.AssertionFailedError: should find one query failed
XPath: //result[@numFound=1] xml response was: <?xml version="1.0"
encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int
name="QTime">0</int></lst><result name="response" numFound="0"
start="0"/>
</response>
at org.apache.solr.util.AbstractSolrTestCase.assertQ(AbstractSolrTestCase.java:157)
at org.apache.solr.update.AutoCommitTest.testMaxDocs(AutoCommitTest.java:98)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at com.intellij.rt.execution.junit.JUnitStarter.main(JUnitStarter.java:40)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at com.intellij.rt.execution.application.AppMain.main(AppMain.java:90)
Re: AutoCommitTest failing
Posted by Ryan McKinley <ry...@gmail.com>.
Can you tell specifically what part triggers the bad behavior? or is
it whenever autocommit gets triggered?
I wish I could be more useful, but from my system (win XP,
jdk1.5.0_07) things look ok...
On 2/4/07, Yonik Seeley <yo...@apache.org> wrote:
> On 2/4/07, Ryan McKinley <ry...@gmail.com> wrote:
> > is this on your machine or on the apache machine? I have never had a
> > problem running on my machine.
>
> My local machine.... happens every time now.
> I think Erik said it was failing for him also.
>
> -Yonik
>
Re: AutoCommitTest failing
Posted by Yonik Seeley <yo...@apache.org>.
On 2/4/07, Ryan McKinley <ry...@gmail.com> wrote:
> is this on your machine or on the apache machine? I have never had a
> problem running on my machine.
My local machine.... happens every time now.
I think Erik said it was failing for him also.
-Yonik
Re: AutoCommitTest failing
Posted by Ryan McKinley <ry...@gmail.com>.
is this on your machine or on the apache machine? I have never had a
problem running on my machine.
The previous error made more sense to me.. it looked like commit did
not actually complete within the given time period. This one seems
more like a Problem.
Do we need to explicitly handle the ThreadPoolExecutor.AbortPolicy?
http://java.sun.com/j2se/1.5.0/docs/api/java/util/concurrent/ThreadPoolExecutor.AbortPolicy.html
ryan
On 2/4/07, Yonik Seeley <yo...@apache.org> wrote:
> On 2/4/07, Yonik Seeley <yo...@apache.org> wrote:
> > With the latest changes to AutoCommitTest, now I'm getting failures:
> >
> > [junit] Tests run: 2, Failures: 1, Errors: 0, Time elapsed: 5.593 sec
> > [junit] Test org.apache.solr.update.AutoCommitTest FAILED
>
Re: AutoCommitTest failing
Posted by Walter Underwood <wu...@netflix.com>.
On 2/5/07 11:18 AM, "Yonik Seeley" <yo...@apache.org> wrote:
>
> Yes, I think that's it.
> SolrCore.close() shuts down the Executor.
> From the trace, you can see SolrCore closing, then an attempt to open
> up another searcher after that.
>
> The close of the update handler should probably shut down it's executor too.
That is one cause, according to the docs:
New tasks submitted in method execute(java.lang.Runnable) will be rejected
when the Executor has been shut down, and also when the Executor uses
finite bounds for both maximum threads and work queue capacity, and is
saturated.
http://java.sun.com/j2se/1.5.0/docs/api/java/util/concurrent/ThreadPoolExecu
tor.html
wunder
Re: AutoCommitTest failing
Posted by Yonik Seeley <yo...@apache.org>.
On 2/5/07, Ryan McKinley <ry...@gmail.com> wrote:
> >
> > Getting the test to pass is one thing... but this exception is
> > another. Is it possible that this could be triggered by normal use of
> > Solr? Perhaps by too-frequent commits?
> >
>
> I don't *think* so... I haven't seen any trouble while load testing
> this on a real system.
>
> I *think* the RejectedExecutionException is thrown when JUnit aborts
> all execution. Essentially this is the ScheduledFutureTask saying
> "hey! i didn't get to complete even though you told me to"
Yes, I think that's it.
SolrCore.close() shuts down the Executor.
>From the trace, you can see SolrCore closing, then an attempt to open
up another searcher after that.
The close of the update handler should probably shut down it's executor too.
-Yonik
Re: AutoCommitTest failing
Posted by Mike Klaas <mi...@gmail.com>.
On 2/5/07, Ryan McKinley <ry...@gmail.com> wrote:
> >
> > Getting the test to pass is one thing... but this exception is
> > another. Is it possible that this could be triggered by normal use of
> > Solr? Perhaps by too-frequent commits?
> >
>
> I don't *think* so... I haven't seen any trouble while load testing
> this on a real system.
>
> I *think* the RejectedExecutionException is thrown when JUnit aborts
> all execution. Essentially this is the ScheduledFutureTask saying
> "hey! i didn't get to complete even though you told me to"
That's reassuring.
I'll try to come up with a proposed fix in about an hour when I have a
bit of free time.
-Mike
Re: AutoCommitTest failing
Posted by Ryan McKinley <ry...@gmail.com>.
>
> Getting the test to pass is one thing... but this exception is
> another. Is it possible that this could be triggered by normal use of
> Solr? Perhaps by too-frequent commits?
>
I don't *think* so... I haven't seen any trouble while load testing
this on a real system.
I *think* the RejectedExecutionException is thrown when JUnit aborts
all execution. Essentially this is the ScheduledFutureTask saying
"hey! i didn't get to complete even though you told me to"
Re: AutoCommitTest failing
Posted by Mike Klaas <mi...@gmail.com>.
On 2/4/07, Yonik Seeley <yo...@apache.org> wrote:
>
> Stack trace:
>
> INFO: Opening Searcher@1c6572b main
> Feb 4, 2007 5:19:41 PM org.apache.solr.core.SolrException log
> SEVERE: java.util.concurrent.RejectedExecutionException
> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:1477)
> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:384)
> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:856)
> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:45)
> at java.util.concurrent.Executors$DelegatedExecutorService.submit(Executors.java:606)
> at org.apache.solr.core.SolrCore.getSearcher(SolrCore.java:486)
> at org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:514)
> at org.apache.solr.update.DirectUpdateHandler2$CommitTracker.run(DirectUpdateHandler2.java:625)
> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
> at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
> at java.util.concurrent.FutureTask.run(FutureTask.java:123)
> at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:65)
> at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:168)
> at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
> at java.lang.Thread.run(Thread.java:595)
Getting the test to pass is one thing... but this exception is
another. Is it possible that this could be triggered by normal use of
Solr? Perhaps by too-frequent commits?
-Mike
Re: AutoCommitTest failing
Posted by Yonik Seeley <yo...@apache.org>.
On 2/4/07, Yonik Seeley <yo...@apache.org> wrote:
> With the latest changes to AutoCommitTest, now I'm getting failures:
>
> [junit] Tests run: 2, Failures: 1, Errors: 0, Time elapsed: 5.593 sec
> [junit] Test org.apache.solr.update.AutoCommitTest FAILED
Stack trace:
INFO: Opening Searcher@1c6572b main
Feb 4, 2007 5:19:41 PM org.apache.solr.core.SolrException log
SEVERE: java.util.concurrent.RejectedExecutionException
at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:1477)
at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:384)
at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:856)
at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:45)
at java.util.concurrent.Executors$DelegatedExecutorService.submit(Executors.java:606)
at org.apache.solr.core.SolrCore.getSearcher(SolrCore.java:486)
at org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:514)
at org.apache.solr.update.DirectUpdateHandler2$CommitTracker.run(DirectUpdateHandler2.java:625)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:417)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:269)
at java.util.concurrent.FutureTask.run(FutureTask.java:123)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:65)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:168)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:650)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:675)
at java.lang.Thread.run(Thread.java:595)
-Yonik