You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/12/28 00:54:38 UTC
[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.7.0_60-ea-b01) -
Build # 8851 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/8851/
Java: 64bit/jdk1.7.0_60-ea-b01 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
Error Message:
ERROR: SolrIndexSearcher opens=35 closes=34
Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=35 closes=34
at __randomizedtesting.SeedInfo.seed([619F967943CB3723]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:335)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:139)
at sun.reflect.GeneratedMethodAccessor26.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:700)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:744)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
Error Message:
2 threads leaked from SUITE scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest: 1) Thread[id=1823, name=searcherExecutor-1128-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744) 2) Thread[id=1815, name=qtp1979943673-1815, state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.blockUntilFinished(ConcurrentUpdateSolrServer.java:391) at org.apache.solr.update.StreamingSolrServers.blockUntilFinished(StreamingSolrServers.java:104) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:148) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149) at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:71) at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:606) at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1456) at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:179) at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135) at org.apache.solr.core.SolrCore.execute(SolrCore.java:1874) at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:753) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:200) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) at org.eclipse.jetty.server.Server.handle(Server.java:368) at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953) at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014) at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953) at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240) at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82) at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628) at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) at java.lang.Thread.run(Thread.java:744)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE scope at org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest:
1) Thread[id=1823, name=searcherExecutor-1128-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
2) Thread[id=1815, name=qtp1979943673-1815, state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest]
at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.blockUntilFinished(ConcurrentUpdateSolrServer.java:391)
at org.apache.solr.update.StreamingSolrServers.blockUntilFinished(StreamingSolrServers.java:104)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:148)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:71)
at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:606)
at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1456)
at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:179)
at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:1874)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:753)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:200)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
at org.eclipse.jetty.server.Server.handle(Server.java:368)
at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
at java.lang.Thread.run(Thread.java:744)
at __randomizedtesting.SeedInfo.seed([619F967943CB3723]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=1815, name=qtp1979943673-1815, state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest] at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.blockUntilFinished(ConcurrentUpdateSolrServer.java:391) at org.apache.solr.update.StreamingSolrServers.blockUntilFinished(StreamingSolrServers.java:104) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:148) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149) at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149) at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:71) at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:606) at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1456) at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:179) at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135) at org.apache.solr.core.SolrCore.execute(SolrCore.java:1874) at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:753) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:200) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137) at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116) at org.eclipse.jetty.server.Server.handle(Server.java:368) at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489) at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953) at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014) at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953) at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240) at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82) at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628) at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543) at java.lang.Thread.run(Thread.java:744) 2) Thread[id=1823, name=searcherExecutor-1128-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:744)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=1815, name=qtp1979943673-1815, state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest]
at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.blockUntilFinished(ConcurrentUpdateSolrServer.java:391)
at org.apache.solr.update.StreamingSolrServers.blockUntilFinished(StreamingSolrServers.java:104)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:148)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:71)
at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:606)
at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1456)
at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:179)
at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:1874)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:753)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:200)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
at org.eclipse.jetty.server.Server.handle(Server.java:368)
at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
at java.lang.Thread.run(Thread.java:744)
2) Thread[id=1823, name=searcherExecutor-1128-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
at __randomizedtesting.SeedInfo.seed([619F967943CB3723]:0)
Build Log:
[...truncated 10517 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
[junit4] 2> 421175 T1741 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /b/
[junit4] 2> 421180 T1741 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ChaosMonkeyNothingIsSafeTest-1388188120670
[junit4] 2> 421180 T1741 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 421181 T1742 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 421281 T1741 oasc.ZkTestServer.run start zk server on port:51782
[junit4] 2> 421282 T1741 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 421460 T1748 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7bcdf9f3 name:ZooKeeperConnection Watcher:127.0.0.1:51782 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 421460 T1741 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 421460 T1741 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 421465 T1741 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 421466 T1750 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@25f5f7a name:ZooKeeperConnection Watcher:127.0.0.1:51782/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 421467 T1741 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 421467 T1741 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 421472 T1741 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 421474 T1741 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 421476 T1741 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 421478 T1741 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 421479 T1741 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 421482 T1741 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 421483 T1741 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 421486 T1741 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 421486 T1741 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 421488 T1741 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 421489 T1741 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 421491 T1741 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 421491 T1741 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 421494 T1741 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 421494 T1741 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 421496 T1741 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 421496 T1741 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 421498 T1741 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 421499 T1741 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 421501 T1741 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 421501 T1741 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 421503 T1741 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 421503 T1741 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 421505 T1741 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 421506 T1741 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 421575 T1741 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 421585 T1741 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:38998
[junit4] 2> 421585 T1741 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 421585 T1741 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 421586 T1741 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388188120999
[junit4] 2> 421586 T1741 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388188120999/'
[junit4] 2> 421597 T1741 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388188120999/solr.xml
[junit4] 2> 421617 T1741 oasc.CoreContainer.<init> New CoreContainer 393241374
[junit4] 2> 421618 T1741 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388188120999/]
[junit4] 2> 421618 T1741 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 421619 T1741 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 421619 T1741 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 421619 T1741 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 421625 T1741 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 421626 T1741 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 421626 T1741 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 421626 T1741 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 421626 T1741 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 421629 T1741 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 421630 T1741 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 421630 T1741 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 421630 T1741 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51782/solr
[junit4] 2> 421630 T1741 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 421631 T1741 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 421633 T1761 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2bdd942 name:ZooKeeperConnection Watcher:127.0.0.1:51782 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 421633 T1741 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 421635 T1741 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 421636 T1763 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7a15bdab name:ZooKeeperConnection Watcher:127.0.0.1:51782/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 421637 T1741 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 421638 T1741 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 421641 T1741 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 421643 T1741 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 421645 T1741 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38998_b
[junit4] 2> 421646 T1741 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:38998_b
[junit4] 2> 421648 T1741 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 421651 T1741 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 421653 T1741 oasc.Overseer.start Overseer (id=90976296676360195-127.0.0.1:38998_b-n_0000000000) starting
[junit4] 2> 421655 T1741 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 421658 T1765 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 421659 T1741 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 421661 T1741 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 421663 T1741 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 421665 T1764 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 421666 T1766 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 421667 T1766 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 421668 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 421668 T1766 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 421670 T1764 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 421671 T1764 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38998/b",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38998_b",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 421671 T1764 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 421671 T1764 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 421673 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 421674 T1763 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 422669 T1766 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 422669 T1766 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388188120999/collection1
[junit4] 2> 422669 T1766 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 422670 T1766 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 422670 T1766 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 422671 T1766 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388188120999/collection1/'
[junit4] 2> 422672 T1766 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388188120999/collection1/lib/classes/' to classloader
[junit4] 2> 422672 T1766 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388188120999/collection1/lib/README' to classloader
[junit4] 2> 422702 T1766 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 422729 T1766 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 422731 T1766 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 422736 T1766 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 422989 T1766 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 422990 T1766 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 422992 T1766 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 422995 T1766 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 423016 T1766 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 423016 T1766 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1388188120999/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/control/data/
[junit4] 2> 423017 T1766 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6947d198
[junit4] 2> 423017 T1766 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/control/data
[junit4] 2> 423017 T1766 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/control/data/index/
[junit4] 2> 423017 T1766 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 423018 T1766 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/control/data/index
[junit4] 2> 423018 T1766 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@5287e749
[junit4] 2> 423020 T1766 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 423021 T1766 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 423023 T1766 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 423024 T1766 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 423024 T1766 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 423024 T1766 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 423024 T1766 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 423024 T1766 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 423025 T1766 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 423025 T1766 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 423025 T1766 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 423025 T1766 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 423026 T1766 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 423026 T1766 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 423026 T1766 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 423026 T1766 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 423027 T1766 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 423027 T1766 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 423027 T1766 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 423030 T1766 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 423031 T1766 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 423031 T1766 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 423032 T1766 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=808254452, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 423033 T1766 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 423033 T1766 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 423033 T1766 oass.SolrIndexSearcher.<init> Opening Searcher@3b410348 main
[junit4] 2> 423035 T1767 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3b410348 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 423036 T1766 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 423037 T1741 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 423037 T1741 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 423037 T1770 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:38998/b collection:control_collection shard:shard1
[junit4] 2> 423038 T1770 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 423039 T1741 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 423040 T1772 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7ec76865 name:ZooKeeperConnection Watcher:127.0.0.1:51782/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 423040 T1741 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 423042 T1741 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 423044 T1741 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 423044 T1770 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 423046 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 423047 T1770 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 423047 T1770 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2542 name=collection1 org.apache.solr.core.SolrCore@212e8d64 url=http://127.0.0.1:38998/b/collection1 node=127.0.0.1:38998_b C2542_STATE=coll:control_collection core:collection1 props:{state=down, base_url=http://127.0.0.1:38998/b, core=collection1, node_name=127.0.0.1:38998_b}
[junit4] 2> 423047 T1770 C2542 P38998 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:38998/b/collection1/
[junit4] 2> 423047 T1770 C2542 P38998 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 423048 T1770 C2542 P38998 oasc.SyncStrategy.syncToMe http://127.0.0.1:38998/b/collection1/ has no replicas
[junit4] 2> 423048 T1770 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:38998/b/collection1/ shard1
[junit4] 2> 423048 T1770 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 423049 T1764 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 423053 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 423055 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 423058 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 423116 T1741 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 423118 T1741 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59621
[junit4] 2> 423118 T1741 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 423119 T1741 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 423119 T1741 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388188122534
[junit4] 2> 423119 T1741 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388188122534/'
[junit4] 2> 423137 T1741 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388188122534/solr.xml
[junit4] 2> 423159 T1741 oasc.CoreContainer.<init> New CoreContainer 1380798912
[junit4] 2> 423159 T1741 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388188122534/]
[junit4] 2> 423160 T1741 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 423161 T1741 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 423161 T1741 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 423161 T1741 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 423161 T1741 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 423161 T1741 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 423161 T1763 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 423161 T1772 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 423162 T1741 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 423163 T1741 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 423163 T1741 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 423167 T1741 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 423167 T1741 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 423167 T1741 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 423168 T1741 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51782/solr
[junit4] 2> 423168 T1741 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 423169 T1741 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 423170 T1783 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6275ee95 name:ZooKeeperConnection Watcher:127.0.0.1:51782 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 423171 T1741 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 423173 T1741 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 423174 T1785 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6baee508 name:ZooKeeperConnection Watcher:127.0.0.1:51782/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 423174 T1741 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 423178 T1741 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 423214 T1770 oasc.ZkController.register We are http://127.0.0.1:38998/b/collection1/ and leader is http://127.0.0.1:38998/b/collection1/
[junit4] 2> 423215 T1770 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:38998/b
[junit4] 2> 423215 T1770 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 423215 T1770 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 423215 T1770 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 423217 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 423217 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 423217 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 423218 T1770 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 423219 T1764 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 423220 T1764 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:38998/b",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38998_b",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 423222 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 423325 T1785 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 423325 T1772 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 423325 T1763 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 424181 T1741 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59621_b
[junit4] 2> 424182 T1741 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59621_b
[junit4] 2> 424184 T1772 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 424184 T1763 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 424184 T1785 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 424190 T1786 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 424190 T1786 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 424191 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 424191 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 424191 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 424191 T1786 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 424193 T1764 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 424193 T1764 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59621/b",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:59621_b",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 424194 T1764 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 424194 T1764 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 424196 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 424298 T1772 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 424298 T1785 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 424298 T1763 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 425192 T1786 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 425192 T1786 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388188122534/collection1
[junit4] 2> 425192 T1786 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 425194 T1786 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 425194 T1786 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 425195 T1786 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388188122534/collection1/'
[junit4] 2> 425196 T1786 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388188122534/collection1/lib/classes/' to classloader
[junit4] 2> 425196 T1786 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388188122534/collection1/lib/README' to classloader
[junit4] 2> 425229 T1786 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 425255 T1786 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 425256 T1786 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 425262 T1786 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 425499 T1786 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 425501 T1786 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 425502 T1786 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 425505 T1786 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 425527 T1786 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 425527 T1786 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1388188122534/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty1/
[junit4] 2> 425527 T1786 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6947d198
[junit4] 2> 425528 T1786 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty1
[junit4] 2> 425528 T1786 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty1/index/
[junit4] 2> 425529 T1786 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 425529 T1786 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty1/index
[junit4] 2> 425529 T1786 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@b3dd74b
[junit4] 2> 425532 T1786 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 425533 T1786 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 425537 T1786 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 425538 T1786 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 425538 T1786 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 425538 T1786 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 425538 T1786 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 425538 T1786 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 425539 T1786 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 425539 T1786 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 425539 T1786 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 425540 T1786 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 425541 T1786 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 425541 T1786 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 425541 T1786 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 425542 T1786 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 425542 T1786 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 425543 T1786 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 425543 T1786 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 425546 T1786 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 425548 T1786 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 425548 T1786 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 425549 T1786 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=808254452, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 425550 T1786 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 425551 T1786 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 425551 T1786 oass.SolrIndexSearcher.<init> Opening Searcher@520180ce main
[junit4] 2> 425555 T1787 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@520180ce main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 425558 T1786 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 425559 T1741 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 425559 T1741 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 425559 T1790 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:59621/b collection:collection1 shard:shard1
[junit4] 2> 425560 T1790 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 425566 T1790 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 425568 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 425568 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 425568 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 425568 T1790 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 425569 T1790 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2543 name=collection1 org.apache.solr.core.SolrCore@f2e421 url=http://127.0.0.1:59621/b/collection1 node=127.0.0.1:59621_b C2543_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:59621/b, core=collection1, node_name=127.0.0.1:59621_b}
[junit4] 2> 425569 T1790 C2543 P59621 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:59621/b/collection1/
[junit4] 2> 425569 T1790 C2543 P59621 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 425569 T1790 C2543 P59621 oasc.SyncStrategy.syncToMe http://127.0.0.1:59621/b/collection1/ has no replicas
[junit4] 2> 425569 T1790 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:59621/b/collection1/ shard1
[junit4] 2> 425569 T1790 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 425570 T1764 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 425573 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 425575 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 425578 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 425656 T1741 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 425659 T1741 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:33327
[junit4] 2> 425659 T1741 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 425660 T1741 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 425660 T1741 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388188125050
[junit4] 2> 425660 T1741 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388188125050/'
[junit4] 2> 425681 T1772 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 425681 T1763 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 425681 T1785 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 425682 T1741 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388188125050/solr.xml
[junit4] 2> 425705 T1741 oasc.CoreContainer.<init> New CoreContainer 600911490
[junit4] 2> 425705 T1741 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388188125050/]
[junit4] 2> 425706 T1741 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 425707 T1741 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 425707 T1741 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 425707 T1741 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 425707 T1741 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 425707 T1741 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 425708 T1741 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 425708 T1741 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 425708 T1741 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 425712 T1741 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 425712 T1741 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 425712 T1741 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 425713 T1741 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51782/solr
[junit4] 2> 425713 T1741 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 425714 T1741 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 425715 T1801 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3a6f9626 name:ZooKeeperConnection Watcher:127.0.0.1:51782 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 425716 T1741 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 425718 T1741 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 425720 T1803 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3989443e name:ZooKeeperConnection Watcher:127.0.0.1:51782/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 425720 T1741 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 425723 T1741 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 425727 T1790 oasc.ZkController.register We are http://127.0.0.1:59621/b/collection1/ and leader is http://127.0.0.1:59621/b/collection1/
[junit4] 2> 425728 T1790 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:59621/b
[junit4] 2> 425728 T1790 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 425728 T1790 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 425728 T1790 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 425729 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 425730 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 425730 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 425730 T1790 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 425731 T1764 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 425732 T1764 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:59621/b",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:59621_b",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 425735 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 425837 T1763 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 425837 T1785 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 425837 T1772 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 425838 T1803 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 426727 T1741 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33327_b
[junit4] 2> 426729 T1741 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:33327_b
[junit4] 2> 426734 T1763 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 426734 T1785 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 426734 T1803 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 426734 T1772 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 426744 T1804 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 426744 T1804 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 426746 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 426747 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 426747 T1804 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 426747 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 426750 T1764 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 426751 T1764 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33327/b",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:33327_b",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 426752 T1764 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 426752 T1764 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 426758 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 426861 T1772 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 426861 T1763 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 426861 T1803 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 426862 T1785 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 427747 T1804 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 427748 T1804 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388188125050/collection1
[junit4] 2> 427748 T1804 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 427749 T1804 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 427749 T1804 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 427750 T1804 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388188125050/collection1/'
[junit4] 2> 427750 T1804 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388188125050/collection1/lib/classes/' to classloader
[junit4] 2> 427751 T1804 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388188125050/collection1/lib/README' to classloader
[junit4] 2> 427777 T1804 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 427803 T1804 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 427804 T1804 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 427810 T1804 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 428059 T1804 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 428061 T1804 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 428063 T1804 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 428066 T1804 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 428091 T1804 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 428091 T1804 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1388188125050/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty2/
[junit4] 2> 428092 T1804 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6947d198
[junit4] 2> 428092 T1804 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty2
[junit4] 2> 428093 T1804 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty2/index/
[junit4] 2> 428093 T1804 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 428093 T1804 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty2/index
[junit4] 2> 428094 T1804 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@682f468a
[junit4] 2> 428097 T1804 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 428097 T1804 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 428101 T1804 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 428101 T1804 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 428102 T1804 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 428102 T1804 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 428102 T1804 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 428103 T1804 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 428103 T1804 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 428104 T1804 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 428104 T1804 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 428104 T1804 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 428105 T1804 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 428105 T1804 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 428106 T1804 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 428106 T1804 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 428107 T1804 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 428107 T1804 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 428108 T1804 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler
[junit4] 2> 428111 T1804 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 428113 T1804 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 428113 T1804 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 428114 T1804 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=808254452, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 428115 T1804 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1388188120670/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 428115 T1804 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 428115 T1804 oass.SolrIndexSearcher.<init> Opening Searcher@67f07965 main
[junit4] 2> 428118 T1805 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67f07965 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 428119 T1804 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 428120 T1741 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 428120 T1741 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 428120 T1808 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:33327/b collection:collection1 shard:shard2
[junit4] 2> 428121 T1808 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 428129 T1808 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 428133 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 428133 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 428133 T1763 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 428134 T1808 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 428134 T1808 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C2544 name=collection1 org.apache.solr.core.SolrCore@18d49e3e url=http://127.0.0.1:33327/b/collection1 node=127.0.0.1:33327_b C2544_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:33327/b, core=collection1, node_name=127.0.0.1:33327_b}
[junit4] 2> 428134 T1808 C2544 P33327 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:33327/b/collection1/
[junit4] 2> 428134 T1808 C2544 P33327 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 428134 T1808 C2544 P33327 oasc.SyncStrategy.syncToMe http://127.0.0.1:33327/b/collection1/ has no replicas
[junit4] 2> 428134 T1764 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper.
[...truncated too long message...]
ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] > at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4] > at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4] > at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4] > at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] > at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] > at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > at __randomizedtesting.SeedInfo.seed([619F967943CB3723]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=1815, name=qtp1979943673-1815, state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] > at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.blockUntilFinished(ConcurrentUpdateSolrServer.java:391)
[junit4] > at org.apache.solr.update.StreamingSolrServers.blockUntilFinished(StreamingSolrServers.java:104)
[junit4] > at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:148)
[junit4] > at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
[junit4] > at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
[junit4] > at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
[junit4] > at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
[junit4] > at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:149)
[junit4] > at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:71)
[junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.doFinish(DistributedUpdateProcessor.java:606)
[junit4] > at org.apache.solr.update.processor.DistributedUpdateProcessor.finish(DistributedUpdateProcessor.java:1456)
[junit4] > at org.apache.solr.update.processor.LogUpdateProcessor.finish(LogUpdateProcessorFactory.java:179)
[junit4] > at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:83)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:1874)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:753)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:200)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:136)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] > at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4] > at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4] > at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4] > at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4] > at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4] > at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4] > at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > 2) Thread[id=1823, name=searcherExecutor-1128-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] > at __randomizedtesting.SeedInfo.seed([619F967943CB3723]:0)
[junit4] Completed on J1 in 311.17s, 1 test, 1 failure, 2 errors <<< FAILURES!
[...truncated 595 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:453: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:433: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:491: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1304: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:937: There were test failures: 357 suites, 1573 tests, 3 suite-level errors, 727 ignored
Total time: 37 minutes 1 second
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_60-ea-b01 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Linux (64bit/ibm-j9-jdk7) - Build #
8852 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/8852/
Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
4 tests failed.
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.powerTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='pr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">46</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="dr"> <long name="count">100</long> <double name="mc">0.2891</double> <double name="mean">28.91</double> <double name="su">80.30555555555556</double> <double name="sum">2891.0</double> <long name="unique">36</long> </lst> <lst name="ar"> <long name="count">100</long> <double name="mcm">153.41</double> <double name="mean">28.91</double> <double name="median">24.5</double> <double name="su">2927.0</double> <double name="sum">2891.0</double> <long name="unique">36</long> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="mr"> <long name="count">100</long> <double name="mcm">70829.5</double> <double name="mean">28.91</double> <double name="median">24.5</double> <double name="su">104076.0</double> <double name="sum">2891.0</double> <long name="unique">36</long> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='pr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">46</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="dr">
<long name="count">100</long>
<double name="mc">0.2891</double>
<double name="mean">28.91</double>
<double name="su">80.30555555555556</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</lst>
<lst name="ar">
<long name="count">100</long>
<double name="mcm">153.41</double>
<double name="mean">28.91</double>
<double name="median">24.5</double>
<double name="su">2927.0</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="mr">
<long name="count">100</long>
<double name="mcm">70829.5</double>
<double name="mean">28.91</double>
<double name="median">24.5</double>
<double name="su">104076.0</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([2D43837BA0607580:43F1347848772B51]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
at org.apache.solr.analytics.expression.ExpressionTest.powerTest(ExpressionTest.java:119)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:780)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.absoluteValueTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='avr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">46</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="dr"> <long name="count">100</long> <double name="mc">0.2891</double> <double name="mean">28.91</double> <double name="su">80.30555555555556</double> <double name="sum">2891.0</double> <long name="unique">36</long> </lst> <lst name="ar"> <long name="count">100</long> <double name="mcm">153.41</double> <double name="mean">28.91</double> <double name="median">24.5</double> <double name="su">2927.0</double> <double name="sum">2891.0</double> <long name="unique">36</long> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="mr"> <long name="count">100</long> <double name="mcm">70829.5</double> <double name="mean">28.91</double> <double name="median">24.5</double> <double name="su">104076.0</double> <double name="sum">2891.0</double> <long name="unique">36</long> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='avr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">46</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="dr">
<long name="count">100</long>
<double name="mc">0.2891</double>
<double name="mean">28.91</double>
<double name="su">80.30555555555556</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</lst>
<lst name="ar">
<long name="count">100</long>
<double name="mcm">153.41</double>
<double name="mean">28.91</double>
<double name="median">24.5</double>
<double name="su">2927.0</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="mr">
<long name="count">100</long>
<double name="mcm">70829.5</double>
<double name="mean">28.91</double>
<double name="median">24.5</double>
<double name="su">104076.0</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([2D43837BA0607580:CEFA36E6DFD2DE6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
at org.apache.solr.analytics.expression.ExpressionTest.absoluteValueTest(ExpressionTest.java:143)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:780)
REGRESSION: org.apache.solr.analytics.expression.ExpressionTest.negateTest
Error Message:
Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='nr']/double[@name='sum'] raw data: <?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"> <int name="status">0</int> <int name="QTime">46</int> </lst> <result name="response" numFound="100" start="0"> </result> <lst name="stats"> <lst name="stats_fields"/> </lst> <lst name="stats"> <lst name="pr"> <long name="count">100</long> <null name="mc"/> <null name="mean"/> <null name="su"/> <null name="sum"/> <null name="unique"/> </lst> <lst name="cr"> <str name="ccmax">this is the secondstr9</str> <str name="ccmin">this is the firststr0</str> <str name="csmax">this is the second</str> <str name="csmin">this is the first</str> <str name="max">str9</str> <str name="min">str0</str> </lst> <lst name="rr"> <str name="max">str9</str> <str name="min">str0</str> <str name="rmax">9rts</str> <str name="rmin">0rts</str> </lst> <lst name="dr"> <long name="count">100</long> <double name="mc">0.2891</double> <double name="mean">28.91</double> <double name="su">80.30555555555556</double> <double name="sum">2891.0</double> <long name="unique">36</long> </lst> <lst name="ar"> <long name="count">100</long> <double name="mcm">153.41</double> <double name="mean">28.91</double> <double name="median">24.5</double> <double name="su">2927.0</double> <double name="sum">2891.0</double> <long name="unique">36</long> </lst> <lst name="nr"> <double name="c">-100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="mr"> <long name="count">100</long> <double name="mcm">70829.5</double> <double name="mean">28.91</double> <double name="median">24.5</double> <double name="su">104076.0</double> <double name="sum">2891.0</double> <long name="unique">36</long> </lst> <lst name="avr"> <double name="c">100.0</double> <long name="count">100</long> <null name="s"/> <null name="sum"/> </lst> <lst name="cdr"> <date name="cd1">1800-12-31T23:59:59Z</date> <date name="cd2">1804-06-30T23:59:59Z</date> <str name="cs1">1800-12-31T23:59:59Z</str> <str name="cs2">1804-06-30T23:59:59Z</str> </lst> <lst name="cnr"> <double name="c10">10.0</double> <double name="c8">8.0</double> </lst> <lst name="dmr"> <str name="cma">+2MONTHS</str> <str name="cme">+2YEARS</str> <date name="dmma">1812-02-29T23:59:59Z</date> <date name="dmme">1807-12-31T23:59:59Z</date> <date name="max">1811-12-31T23:59:59Z</date> <date name="median">1805-12-31T23:59:59Z</date> </lst> <lst name="csr"> <str name="cs1">this is the first</str> <str name="cs2">this is the second</str> <str name="cs3">this is the third</str> </lst> </lst> </response>
Stack Trace:
java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='nr']/double[@name='sum']
raw data: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader">
<int name="status">0</int>
<int name="QTime">46</int>
</lst>
<result name="response" numFound="100" start="0">
</result>
<lst name="stats">
<lst name="stats_fields"/>
</lst>
<lst name="stats">
<lst name="pr">
<long name="count">100</long>
<null name="mc"/>
<null name="mean"/>
<null name="su"/>
<null name="sum"/>
<null name="unique"/>
</lst>
<lst name="cr">
<str name="ccmax">this is the secondstr9</str>
<str name="ccmin">this is the firststr0</str>
<str name="csmax">this is the second</str>
<str name="csmin">this is the first</str>
<str name="max">str9</str>
<str name="min">str0</str>
</lst>
<lst name="rr">
<str name="max">str9</str>
<str name="min">str0</str>
<str name="rmax">9rts</str>
<str name="rmin">0rts</str>
</lst>
<lst name="dr">
<long name="count">100</long>
<double name="mc">0.2891</double>
<double name="mean">28.91</double>
<double name="su">80.30555555555556</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</lst>
<lst name="ar">
<long name="count">100</long>
<double name="mcm">153.41</double>
<double name="mean">28.91</double>
<double name="median">24.5</double>
<double name="su">2927.0</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</lst>
<lst name="nr">
<double name="c">-100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="mr">
<long name="count">100</long>
<double name="mcm">70829.5</double>
<double name="mean">28.91</double>
<double name="median">24.5</double>
<double name="su">104076.0</double>
<double name="sum">2891.0</double>
<long name="unique">36</long>
</lst>
<lst name="avr">
<double name="c">100.0</double>
<long name="count">100</long>
<null name="s"/>
<null name="sum"/>
</lst>
<lst name="cdr">
<date name="cd1">1800-12-31T23:59:59Z</date>
<date name="cd2">1804-06-30T23:59:59Z</date>
<str name="cs1">1800-12-31T23:59:59Z</str>
<str name="cs2">1804-06-30T23:59:59Z</str>
</lst>
<lst name="cnr">
<double name="c10">10.0</double>
<double name="c8">8.0</double>
</lst>
<lst name="dmr">
<str name="cma">+2MONTHS</str>
<str name="cme">+2YEARS</str>
<date name="dmma">1812-02-29T23:59:59Z</date>
<date name="dmme">1807-12-31T23:59:59Z</date>
<date name="max">1811-12-31T23:59:59Z</date>
<date name="median">1805-12-31T23:59:59Z</date>
</lst>
<lst name="csr">
<str name="cs1">this is the first</str>
<str name="cs2">this is the second</str>
<str name="cs3">this is the third</str>
</lst>
</lst>
</response>
at __randomizedtesting.SeedInfo.seed([2D43837BA0607580:254BEBDE044D2530]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
at org.apache.solr.analytics.expression.ExpressionTest.negateTest(ExpressionTest.java:132)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:780)
REGRESSION: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection
Error Message:
expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
at __randomizedtesting.SeedInfo.seed([2D43837BA0607580:767378B3A327D6FC]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection(SignatureUpdateProcessorFactoryTest.java:119)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:780)
Build Log:
[...truncated 10098 lines...]
[junit4] Suite: org.apache.solr.analytics.expression.ExpressionTest
[junit4] 2> 129849 T309 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596
[junit4] 2> 129850 T309 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 129850 T309 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 129851 T309 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 129876 T309 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 129908 T309 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 129909 T309 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 129915 T309 oass.IndexSchema.readSchema [null] Schema name=schema-docValues
[junit4] 2> 129952 T309 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 129953 T309 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 129954 T309 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 129954 T309 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 129964 T309 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 129965 T309 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 129966 T309 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 130025 T309 oasc.CoreContainer.<init> New CoreContainer -1972327284
[junit4] 2> 130026 T309 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 130028 T309 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 130028 T309 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 130029 T309 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 130029 T309 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 130030 T309 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 130030 T309 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 130031 T309 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 130031 T309 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 130032 T309 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 130038 T309 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 130038 T309 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 130039 T309 oasc.CoreContainer.load Host Name:
[junit4] 2> 130043 T310 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 130044 T310 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 130045 T310 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 130045 T310 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 130094 T310 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 130135 T310 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 130135 T310 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
[junit4] 2> 130141 T310 oass.IndexSchema.readSchema [collection1] Schema name=schema-docValues
[junit4] 2> 130178 T310 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 130179 T310 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 130180 T310 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596/
[junit4] 2> 130180 T310 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 130181 T310 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596
[junit4] 2> 130181 T310 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596/index/
[junit4] 2> 130182 T310 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596/index' doesn't exist. Creating new index...
[junit4] 2> 130182 T310 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596/index
[junit4] 2> 130183 T310 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=46.076171875, floorSegmentMB=1.7822265625, forceMergeDeletesPctAllowed=7.233533531916403, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 130184 T310 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@46342363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f5424298),segFN=segments_1,generation=1}
[junit4] 2> 130184 T310 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 130185 T310 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 130186 T310 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 130186 T310 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 130188 T310 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 130190 T310 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 130191 T310 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 130191 T310 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=44.849609375, floorSegmentMB=1.556640625, forceMergeDeletesPctAllowed=5.03079170393778, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4391668287750331
[junit4] 2> 130192 T310 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@46342363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f5424298),segFN=segments_1,generation=1}
[junit4] 2> 130193 T310 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 130193 T310 oass.SolrIndexSearcher.<init> Opening Searcher@6e386002 main
[junit4] 2> 130194 T311 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6e386002 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 130194 T310 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 130196 T309 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> ASYNC NEW_CORE C224 name=collection1 org.apache.solr.core.SolrCore@d5e03e2b
[junit4] 2> 130202 T309 C224 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 130202 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 130210 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10000]} 0 3
[junit4] 2> 130220 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10001]} 0 1
[junit4] 2> 130227 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10002]} 0 1
[junit4] 2> 130234 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10003]} 0 0
[junit4] 2> 130242 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10004]} 0 1
[junit4] 2> 130249 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10005]} 0 1
[junit4] 2> 130256 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10006]} 0 1
[junit4] 2> 130263 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10007]} 0 0
[junit4] 2> 130271 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10008]} 0 1
[junit4] 2> 130278 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10009]} 0 1
[junit4] 2> 130285 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100010]} 0 1
[junit4] 2> 130292 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100011]} 0 0
[junit4] 2> 130300 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100012]} 0 1
[junit4] 2> 130307 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100013]} 0 1
[junit4] 2> 130314 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100014]} 0 0
[junit4] 2> 130322 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100015]} 0 1
[junit4] 2> 130329 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100016]} 0 1
[junit4] 2> 130337 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100017]} 0 1
[junit4] 2> 130344 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100018]} 0 1
[junit4] 2> 130351 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100019]} 0 1
[junit4] 2> 130358 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100020]} 0 0
[junit4] 2> 130366 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100021]} 0 1
[junit4] 2> 130373 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100022]} 0 1
[junit4] 2> 130381 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100023]} 0 1
[junit4] 2> 130388 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100024]} 0 1
[junit4] 2> 130396 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100025]} 0 1
[junit4] 2> 130403 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100026]} 0 1
[junit4] 2> 130410 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100027]} 0 1
[junit4] 2> 130417 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100028]} 0 1
[junit4] 2> 130425 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100029]} 0 1
[junit4] 2> 130432 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100030]} 0 1
[junit4] 2> 130439 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100031]} 0 0
[junit4] 2> 130447 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100032]} 0 1
[junit4] 2> 130454 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100033]} 0 1
[junit4] 2> 130461 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100034]} 0 0
[junit4] 2> 130468 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100035]} 0 0
[junit4] 2> 130475 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100036]} 0 0
[junit4] 2> 130482 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100037]} 0 0
[junit4] 2> 130490 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100038]} 0 1
[junit4] 2> 130497 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100039]} 0 1
[junit4] 2> 130504 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100040]} 0 1
[junit4] 2> 130511 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100041]} 0 0
[junit4] 2> 130518 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100042]} 0 1
[junit4] 2> 130523 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100043]} 0 0
[junit4] 2> 130531 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100044]} 0 1
[junit4] 2> 130538 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100045]} 0 1
[junit4] 2> 130545 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100046]} 0 1
[junit4] 2> 130552 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100047]} 0 1
[junit4] 2> 130559 T309 C224 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100048]} 0 1
[junit4] 2> ASYNC NEW_CORE C225 name=collection1 org.apache.solr.core.SolrCore@d5e03e2b
[junit4] 2> 130578 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100049]} 0 1
[junit4] 2> 130583 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100050]} 0 1
[junit4] 2> 130588 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100051]} 0 1
[junit4] 2> 130593 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100052]} 0 1
[junit4] 2> 130599 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100053]} 0 1
[junit4] 2> 130604 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100054]} 0 1
[junit4] 2> 130609 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100055]} 0 1
[junit4] 2> 130615 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100056]} 0 1
[junit4] 2> 130622 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100057]} 0 1
[junit4] 2> 130627 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100058]} 0 1
[junit4] 2> 130632 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100059]} 0 1
[junit4] 2> 130637 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100060]} 0 1
[junit4] 2> 130642 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100061]} 0 1
[junit4] 2> 130647 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100062]} 0 1
[junit4] 2> 130652 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100063]} 0 1
[junit4] 2> 130656 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100064]} 0 0
[junit4] 2> 130661 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100065]} 0 0
[junit4] 2> 130667 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100066]} 0 1
[junit4] 2> 130676 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100067]} 0 1
[junit4] 2> 130684 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100068]} 0 1
[junit4] 2> 130691 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100069]} 0 0
[junit4] 2> 130699 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100070]} 0 1
[junit4] 2> 130708 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100071]} 0 1
[junit4] 2> 130715 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100072]} 0 1
[junit4] 2> 130723 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100073]} 0 1
[junit4] 2> 130730 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100074]} 0 1
[junit4] 2> 130738 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100075]} 0 1
[junit4] 2> 130746 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100076]} 0 1
[junit4] 2> 130754 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100077]} 0 0
[junit4] 2> 130762 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100078]} 0 1
[junit4] 2> 130770 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100079]} 0 1
[junit4] 2> 130777 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100080]} 0 0
[junit4] 2> 130785 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100081]} 0 1
[junit4] 2> 130792 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100082]} 0 1
[junit4] 2> 130798 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100083]} 0 0
[junit4] 2> 130805 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100084]} 0 0
[junit4] 2> 130812 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100085]} 0 0
[junit4] 2> 130819 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100086]} 0 1
[junit4] 2> 130826 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100087]} 0 1
[junit4] 2> 130833 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100088]} 0 1
[junit4] 2> 130840 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100089]} 0 1
[junit4] 2> 130844 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100090]} 0 0
[junit4] 2> 130849 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100091]} 0 0
[junit4] 2> 130854 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100092]} 0 1
[junit4] 2> 130859 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100093]} 0 1
[junit4] 2> 130864 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100094]} 0 1
[junit4] 2> 130869 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100095]} 0 1
[junit4] 2> 130874 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100096]} 0 0
[junit4] 2> 130878 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100097]} 0 0
[junit4] 2> 130883 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100098]} 0 0
[junit4] 2> 130888 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100099]} 0 0
[junit4] 2> 130892 T309 C225 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 130907 T309 C225 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@46342363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f5424298),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@46342363 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f5424298),segFN=segments_2,generation=2}
[junit4] 2> 130908 T309 C225 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 130918 T309 C225 oass.SolrIndexSearcher.<init> Opening Searcher@177f6e62 main
[junit4] 2> 130918 T309 C225 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 130918 T311 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@177f6e62 main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):C100)}
[junit4] 2> 130919 T309 C225 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 27
[junit4] 2> 130970 T309 C225 oasc.SolrCore.execute [collection1] webapp=null path=null params={o.pr.s.sum=sum(int_id)&o.cdr.s.cd1=const_date(1800-12-31T23:59:59Z)&o.rr.s.rmax=rev(max(string_sd))&o.cdr.s.cs2=const_str(1804-06-30T23:59:59Z)&o.avr.s.sum=sum(int_id)&o.csr.s.cs3=const_str(this+is+the+third)&o.cdr.s.cs1=const_str(1800-12-31T23:59:59Z)&o.rr.s.max=max(string_sd)&o.csr.s.cs2=const_str(this+is+the+second)&o.pr.s.unique=unique(long_ld)&o.mr.s.su=mult(sum(int_id),unique(long_ld))&o.pr.s.mean=mean(int_id)&o.ar.s.su=add(sum(int_id),unique(long_ld))&o.ar.s.unique=unique(long_ld)&o.pr.s.mc=pow(mean(int_id),count(long_ld))&o.nr.s.c=neg(count(long_ld))&o.dr.s.su=div(sum(int_id),unique(long_ld))&o.mr.s.unique=unique(long_ld)&stats=true&o.dr.s.mc=div(mean(int_id),count(long_ld))&o.ar.s.sum=sum(int_id)&o.dmr.s.cme=const_str(%2B2YEARS)&o.mr.s.mean=mean(int_id)&o.ar.s.count=count(long_ld)&o.pr.s.su=pow(sum(int_id),unique(long_ld))&o.mr.s.count=count(long_ld)&olap=true&o.cdr.s.cd2=const_date(1804-06-30T23:59:59Z)&o.dr.s.mean=mean(int_id)&rows=0&o.ar.s.mean=mean(int_id)&o.dmr.s.median=median(date_dtd)&o.nr.s.s=neg(sum(int_id))&o.rr.s.rmin=rev(min(string_sd))&o.pr.s.count=count(long_ld)&o.mr.s.median=median(int_id)&o.mr.s.mcm=mult(mean(int_id),count(long_ld),median(int_id))&o.avr.s.c=abs(neg(count(long_ld)))&q=*:*&o.cr.s.csmin=const_str(this+is+the+first)&o.avr.s.s=abs(neg(sum(int_id)))&o.nr.s.sum=sum(int_id)&o.dmr.s.max=max(date_dtd)&o.nr.s.count=count(long_ld)&o.dr.s.sum=sum(int_id)&o.cr.s.csmax=const_str(this+is+the+second)&o.dmr.s.dmma=date_math(max(date_dtd),const_str(%2B2MONTHS))&o.dr.s.unique=unique(long_ld)&o.dmr.s.dmme=date_math(median(date_dtd),const_str(%2B2YEARS))&o.cr.s.ccmax=concat(const_str(this+is+the+second),max(string_sd))&o.dr.s.count=count(long_ld)&o.avr.s.count=count(long_ld)&o.cr.s.min=min(string_sd)&o.cnr.s.c10=const_num(10)&o.dmr.s.cma=const_str(%2B2MONTHS)&o.ar.s.mcm=add(mean(int_id),count(long_ld),median(int_id))&o.ar.s.median=median(int_id)&o.cr.s.max=max(string_sd)&o.cnr.s.c8=const_num(8)&o.mr.s.sum=sum(int_id)&indent=true&o.rr.s.min=min(string_sd)&o.csr.s.cs1=const_str(this+is+the+first)&o.cr.s.ccmin=concat(const_str(this+is+the+first),min(string_sd))} hits=100 status=0 QTime=46
[junit4] 2> 130979 T309 oas.SolrTestCaseJ4.setUp ###Starting addTest
[junit4] 2> 130986 T309 oas.SolrTestCaseJ4.tearDown ###Ending addTest
[junit4] 2> 130992 T309 oas.SolrTestCaseJ4.setUp ###Starting constantNumberTest
[junit4] 2> 130995 T309 oas.SolrTestCaseJ4.tearDown ###Ending constantNumberTest
[junit4] 2> 130999 T309 oas.SolrTestCaseJ4.setUp ###Starting constantStringTest
[junit4] 2> 131001 T309 oas.SolrTestCaseJ4.tearDown ###Ending constantStringTest
[junit4] 2> 131005 T309 oas.SolrTestCaseJ4.setUp ###Starting powerTest
[junit4] 2> 131006 T309 oas.SolrTestCaseJ4.tearDown ###Ending powerTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ExpressionTest -Dtests.method=powerTest -Dtests.seed=2D43837BA0607580 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ja_JP_JP_#u-ca-japanese -Dtests.timezone=Africa/Bujumbura -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.01s J1 | ExpressionTest.powerTest <<<
[junit4] > Throwable #1: java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='pr']/double[@name='sum']
[junit4] > raw data: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader">
[junit4] > <int name="status">0</int>
[junit4] > <int name="QTime">46</int>
[junit4] > </lst>
[junit4] > <result name="response" numFound="100" start="0">
[junit4] > </result>
[junit4] > <lst name="stats">
[junit4] > <lst name="stats_fields"/>
[junit4] > </lst>
[junit4] > <lst name="stats">
[junit4] > <lst name="pr">
[junit4] > <long name="count">100</long>
[junit4] > <null name="mc"/>
[junit4] > <null name="mean"/>
[junit4] > <null name="su"/>
[junit4] > <null name="sum"/>
[junit4] > <null name="unique"/>
[junit4] > </lst>
[junit4] > <lst name="cr">
[junit4] > <str name="ccmax">this is the secondstr9</str>
[junit4] > <str name="ccmin">this is the firststr0</str>
[junit4] > <str name="csmax">this is the second</str>
[junit4] > <str name="csmin">this is the first</str>
[junit4] > <str name="max">str9</str>
[junit4] > <str name="min">str0</str>
[junit4] > </lst>
[junit4] > <lst name="rr">
[junit4] > <str name="max">str9</str>
[junit4] > <str name="min">str0</str>
[junit4] > <str name="rmax">9rts</str>
[junit4] > <str name="rmin">0rts</str>
[junit4] > </lst>
[junit4] > <lst name="dr">
[junit4] > <long name="count">100</long>
[junit4] > <double name="mc">0.2891</double>
[junit4] > <double name="mean">28.91</double>
[junit4] > <double name="su">80.30555555555556</double>
[junit4] > <double name="sum">2891.0</double>
[junit4] > <long name="unique">36</long>
[junit4] > </lst>
[junit4] > <lst name="ar">
[junit4] > <long name="count">100</long>
[junit4] > <double name="mcm">153.41</double>
[junit4] > <double name="mean">28.91</double>
[junit4] > <double name="median">24.5</double>
[junit4] > <double name="su">2927.0</double>
[junit4] > <double name="sum">2891.0</double>
[junit4] > <long name="unique">36</long>
[junit4] > </lst>
[junit4] > <lst name="nr">
[junit4] > <double name="c">-100.0</double>
[junit4] > <long name="count">100</long>
[junit4] > <null name="s"/>
[junit4] > <null name="sum"/>
[junit4] > </lst>
[junit4] > <lst name="mr">
[junit4] > <long name="count">100</long>
[junit4] > <double name="mcm">70829.5</double>
[junit4] > <double name="mean">28.91</double>
[junit4] > <double name="median">24.5</double>
[junit4] > <double name="su">104076.0</double>
[junit4] > <double name="sum">2891.0</double>
[junit4] > <long name="unique">36</long>
[junit4] > </lst>
[junit4] > <lst name="avr">
[junit4] > <double name="c">100.0</double>
[junit4] > <long name="count">100</long>
[junit4] > <null name="s"/>
[junit4] > <null name="sum"/>
[junit4] > </lst>
[junit4] > <lst name="cdr">
[junit4] > <date name="cd1">1800-12-31T23:59:59Z</date>
[junit4] > <date name="cd2">1804-06-30T23:59:59Z</date>
[junit4] > <str name="cs1">1800-12-31T23:59:59Z</str>
[junit4] > <str name="cs2">1804-06-30T23:59:59Z</str>
[junit4] > </lst>
[junit4] > <lst name="cnr">
[junit4] > <double name="c10">10.0</double>
[junit4] > <double name="c8">8.0</double>
[junit4] > </lst>
[junit4] > <lst name="dmr">
[junit4] > <str name="cma">+2MONTHS</str>
[junit4] > <str name="cme">+2YEARS</str>
[junit4] > <date name="dmma">1812-02-29T23:59:59Z</date>
[junit4] > <date name="dmme">1807-12-31T23:59:59Z</date>
[junit4] > <date name="max">1811-12-31T23:59:59Z</date>
[junit4] > <date name="median">1805-12-31T23:59:59Z</date>
[junit4] > </lst>
[junit4] > <lst name="csr">
[junit4] > <str name="cs1">this is the first</str>
[junit4] > <str name="cs2">this is the second</str>
[junit4] > <str name="cs3">this is the third</str>
[junit4] > </lst>
[junit4] > </lst>
[junit4] > </response>
[junit4] > at __randomizedtesting.SeedInfo.seed([2D43837BA0607580:43F1347848772B51]:0)
[junit4] > at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
[junit4] > at org.apache.solr.analytics.expression.ExpressionTest.powerTest(ExpressionTest.java:119)
[junit4] > at java.lang.Thread.run(Thread.java:780)
[junit4] 2> 131014 T309 oas.SolrTestCaseJ4.setUp ###Starting absoluteValueTest
[junit4] 2> 131016 T309 oas.SolrTestCaseJ4.tearDown ###Ending absoluteValueTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ExpressionTest -Dtests.method=absoluteValueTest -Dtests.seed=2D43837BA0607580 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ja_JP_JP_#u-ca-japanese -Dtests.timezone=Africa/Bujumbura -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.01s J1 | ExpressionTest.absoluteValueTest <<<
[junit4] > Throwable #1: java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='avr']/double[@name='sum']
[junit4] > raw data: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader">
[junit4] > <int name="status">0</int>
[junit4] > <int name="QTime">46</int>
[junit4] > </lst>
[junit4] > <result name="response" numFound="100" start="0">
[junit4] > </result>
[junit4] > <lst name="stats">
[junit4] > <lst name="stats_fields"/>
[junit4] > </lst>
[junit4] > <lst name="stats">
[junit4] > <lst name="pr">
[junit4] > <long name="count">100</long>
[junit4] > <null name="mc"/>
[junit4] > <null name="mean"/>
[junit4] > <null name="su"/>
[junit4] > <null name="sum"/>
[junit4] > <null name="unique"/>
[junit4] > </lst>
[junit4] > <lst name="cr">
[junit4] > <str name="ccmax">this is the secondstr9</str>
[junit4] > <str name="ccmin">this is the firststr0</str>
[junit4] > <str name="csmax">this is the second</str>
[junit4] > <str name="csmin">this is the first</str>
[junit4] > <str name="max">str9</str>
[junit4] > <str name="min">str0</str>
[junit4] > </lst>
[junit4] > <lst name="rr">
[junit4] > <str name="max">str9</str>
[junit4] > <str name="min">str0</str>
[junit4] > <str name="rmax">9rts</str>
[junit4] > <str name="rmin">0rts</str>
[junit4] > </lst>
[junit4] > <lst name="dr">
[junit4] > <long name="count">100</long>
[junit4] > <double name="mc">0.2891</double>
[junit4] > <double name="mean">28.91</double>
[junit4] > <double name="su">80.30555555555556</double>
[junit4] > <double name="sum">2891.0</double>
[junit4] > <long name="unique">36</long>
[junit4] > </lst>
[junit4] > <lst name="ar">
[junit4] > <long name="count">100</long>
[junit4] > <double name="mcm">153.41</double>
[junit4] > <double name="mean">28.91</double>
[junit4] > <double name="median">24.5</double>
[junit4] > <double name="su">2927.0</double>
[junit4] > <double name="sum">2891.0</double>
[junit4] > <long name="unique">36</long>
[junit4] > </lst>
[junit4] > <lst name="nr">
[junit4] > <double name="c">-100.0</double>
[junit4] > <long name="count">100</long>
[junit4] > <null name="s"/>
[junit4] > <null name="sum"/>
[junit4] > </lst>
[junit4] > <lst name="mr">
[junit4] > <long name="count">100</long>
[junit4] > <double name="mcm">70829.5</double>
[junit4] > <double name="mean">28.91</double>
[junit4] > <double name="median">24.5</double>
[junit4] > <double name="su">104076.0</double>
[junit4] > <double name="sum">2891.0</double>
[junit4] > <long name="unique">36</long>
[junit4] > </lst>
[junit4] > <lst name="avr">
[junit4] > <double name="c">100.0</double>
[junit4] > <long name="count">100</long>
[junit4] > <null name="s"/>
[junit4] > <null name="sum"/>
[junit4] > </lst>
[junit4] > <lst name="cdr">
[junit4] > <date name="cd1">1800-12-31T23:59:59Z</date>
[junit4] > <date name="cd2">1804-06-30T23:59:59Z</date>
[junit4] > <str name="cs1">1800-12-31T23:59:59Z</str>
[junit4] > <str name="cs2">1804-06-30T23:59:59Z</str>
[junit4] > </lst>
[junit4] > <lst name="cnr">
[junit4] > <double name="c10">10.0</double>
[junit4] > <double name="c8">8.0</double>
[junit4] > </lst>
[junit4] > <lst name="dmr">
[junit4] > <str name="cma">+2MONTHS</str>
[junit4] > <str name="cme">+2YEARS</str>
[junit4] > <date name="dmma">1812-02-29T23:59:59Z</date>
[junit4] > <date name="dmme">1807-12-31T23:59:59Z</date>
[junit4] > <date name="max">1811-12-31T23:59:59Z</date>
[junit4] > <date name="median">1805-12-31T23:59:59Z</date>
[junit4] > </lst>
[junit4] > <lst name="csr">
[junit4] > <str name="cs1">this is the first</str>
[junit4] > <str name="cs2">this is the second</str>
[junit4] > <str name="cs3">this is the third</str>
[junit4] > </lst>
[junit4] > </lst>
[junit4] > </response>
[junit4] > at __randomizedtesting.SeedInfo.seed([2D43837BA0607580:CEFA36E6DFD2DE6]:0)
[junit4] > at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
[junit4] > at org.apache.solr.analytics.expression.ExpressionTest.absoluteValueTest(ExpressionTest.java:143)
[junit4] > at java.lang.Thread.run(Thread.java:780)
[junit4] 2> 131023 T309 oas.SolrTestCaseJ4.setUp ###Starting multiplyTest
[junit4] 2> 131028 T309 oas.SolrTestCaseJ4.tearDown ###Ending multiplyTest
[junit4] 2> 131032 T309 oas.SolrTestCaseJ4.setUp ###Starting reverseTest
[junit4] 2> 131035 T309 oas.SolrTestCaseJ4.tearDown ###Ending reverseTest
[junit4] 2> 131039 T309 oas.SolrTestCaseJ4.setUp ###Starting dateMathTest
[junit4] 2> 131043 T309 oas.SolrTestCaseJ4.tearDown ###Ending dateMathTest
[junit4] 2> 131053 T309 oas.SolrTestCaseJ4.setUp ###Starting constantDateTest
[junit4] 2> 131056 T309 oas.SolrTestCaseJ4.tearDown ###Ending constantDateTest
[junit4] 2> 131060 T309 oas.SolrTestCaseJ4.setUp ###Starting divideTest
[junit4] 2> 131065 T309 oas.SolrTestCaseJ4.tearDown ###Ending divideTest
[junit4] 2> 131070 T309 oas.SolrTestCaseJ4.setUp ###Starting negateTest
[junit4] 2> 131071 T309 oas.SolrTestCaseJ4.tearDown ###Ending negateTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ExpressionTest -Dtests.method=negateTest -Dtests.seed=2D43837BA0607580 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ja_JP_JP_#u-ca-japanese -Dtests.timezone=Africa/Bujumbura -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.01s J1 | ExpressionTest.negateTest <<<
[junit4] > Throwable #1: java.lang.AssertionError: Caught exception in getStatResult, xPath = /response/lst[@name='stats']/lst[@name='nr']/double[@name='sum']
[junit4] > raw data: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader">
[junit4] > <int name="status">0</int>
[junit4] > <int name="QTime">46</int>
[junit4] > </lst>
[junit4] > <result name="response" numFound="100" start="0">
[junit4] > </result>
[junit4] > <lst name="stats">
[junit4] > <lst name="stats_fields"/>
[junit4] > </lst>
[junit4] > <lst name="stats">
[junit4] > <lst name="pr">
[junit4] > <long name="count">100</long>
[junit4] > <null name="mc"/>
[junit4] > <null name="mean"/>
[junit4] > <null name="su"/>
[junit4] > <null name="sum"/>
[junit4] > <null name="unique"/>
[junit4] > </lst>
[junit4] > <lst name="cr">
[junit4] > <str name="ccmax">this is the secondstr9</str>
[junit4] > <str name="ccmin">this is the firststr0</str>
[junit4] > <str name="csmax">this is the second</str>
[junit4] > <str name="csmin">this is the first</str>
[junit4] > <str name="max">str9</str>
[junit4] > <str name="min">str0</str>
[junit4] > </lst>
[junit4] > <lst name="rr">
[junit4] > <str name="max">str9</str>
[junit4] > <str name="min">str0</str>
[junit4] > <str name="rmax">9rts</str>
[junit4] > <str name="rmin">0rts</str>
[junit4] > </lst>
[junit4] > <lst name="dr">
[junit4] > <long name="count">100</long>
[junit4] > <double name="mc">0.2891</double>
[junit4] > <double name="mean">28.91</double>
[junit4] > <double name="su">80.30555555555556</double>
[junit4] > <double name="sum">2891.0</double>
[junit4] > <long name="unique">36</long>
[junit4] > </lst>
[junit4] > <lst name="ar">
[junit4] > <long name="count">100</long>
[junit4] > <double name="mcm">153.41</double>
[junit4] > <double name="mean">28.91</double>
[junit4] > <double name="median">24.5</double>
[junit4] > <double name="su">2927.0</double>
[junit4] > <double name="sum">2891.0</double>
[junit4] > <long name="unique">36</long>
[junit4] > </lst>
[junit4] > <lst name="nr">
[junit4] > <double name="c">-100.0</double>
[junit4] > <long name="count">100</long>
[junit4] > <null name="s"/>
[junit4] > <null name="sum"/>
[junit4] > </lst>
[junit4] > <lst name="mr">
[junit4] > <long name="count">100</long>
[junit4] > <double name="mcm">70829.5</double>
[junit4] > <double name="mean">28.91</double>
[junit4] > <double name="median">24.5</double>
[junit4] > <double name="su">104076.0</double>
[junit4] > <double name="sum">2891.0</double>
[junit4] > <long name="unique">36</long>
[junit4] > </lst>
[junit4] > <lst name="avr">
[junit4] > <double name="c">100.0</double>
[junit4] > <long name="count">100</long>
[junit4] > <null name="s"/>
[junit4] > <null name="sum"/>
[junit4] > </lst>
[junit4] > <lst name="cdr">
[junit4] > <date name="cd1">1800-12-31T23:59:59Z</date>
[junit4] > <date name="cd2">1804-06-30T23:59:59Z</date>
[junit4] > <str name="cs1">1800-12-31T23:59:59Z</str>
[junit4] > <str name="cs2">1804-06-30T23:59:59Z</str>
[junit4] > </lst>
[junit4] > <lst name="cnr">
[junit4] > <double name="c10">10.0</double>
[junit4] > <double name="c8">8.0</double>
[junit4] > </lst>
[junit4] > <lst name="dmr">
[junit4] > <str name="cma">+2MONTHS</str>
[junit4] > <str name="cme">+2YEARS</str>
[junit4] > <date name="dmma">1812-02-29T23:59:59Z</date>
[junit4] > <date name="dmme">1807-12-31T23:59:59Z</date>
[junit4] > <date name="max">1811-12-31T23:59:59Z</date>
[junit4] > <date name="median">1805-12-31T23:59:59Z</date>
[junit4] > </lst>
[junit4] > <lst name="csr">
[junit4] > <str name="cs1">this is the first</str>
[junit4] > <str name="cs2">this is the second</str>
[junit4] > <str name="cs3">this is the third</str>
[junit4] > </lst>
[junit4] > </lst>
[junit4] > </response>
[junit4] > at __randomizedtesting.SeedInfo.seed([2D43837BA0607580:254BEBDE044D2530]:0)
[junit4] > at org.apache.solr.analytics.AbstractAnalyticsStatsTest.getStatResult(AbstractAnalyticsStatsTest.java:113)
[junit4] > at org.apache.solr.analytics.expression.ExpressionTest.negateTest(ExpressionTest.java:132)
[junit4] > at java.lang.Thread.run(Thread.java:780)
[junit4] 2> 131079 T309 oas.SolrTestCaseJ4.setUp ###Starting concatenateTest
[junit4] 2> 131083 T309 oas.SolrTestCaseJ4.tearDown ###Ending concatenateTest
[junit4] 2> 131085 T309 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 131085 T309 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=-1972327284
[junit4] 2> 131086 T309 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@d5e03e2b
[junit4] 2> 131086 T309 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=100,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 131086 T309 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 131087 T309 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 131087 T309 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 131088 T309 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 131088 T309 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 131088 T309 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596;done=false>>]
[junit4] 2> 131088 T309 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596
[junit4] 2> 131089 T309 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596/index;done=false>>]
[junit4] 2> 131089 T309 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-ExpressionTest-1388192915596/index
[junit4] 2> NOTE: test params are: codec=Lucene46: {long_ld=PostingsFormat(name=SimpleText), float_fd=PostingsFormat(name=Direct), double_dd=PostingsFormat(name=Lucene41WithOrds), date_dtd=PostingsFormat(name=Direct), string_sd=PostingsFormat(name=Direct), int_id=PostingsFormat(name=Direct), id=PostingsFormat(name=SimpleText)}, docValues:{floatdv=DocValuesFormat(name=SimpleText), datedv=DocValuesFormat(name=Memory), stringdv=DocValuesFormat(name=Disk), intdv=DocValuesFormat(name=Lucene45), double_dd=DocValuesFormat(name=SimpleText), date_dtd=DocValuesFormat(name=Disk), int_id=DocValuesFormat(name=Lucene45), stringdvm=DocValuesFormat(name=SimpleText), longdv=DocValuesFormat(name=SimpleText), long_ld=DocValuesFormat(name=Disk), string_sd=DocValuesFormat(name=Lucene45), doubledv=DocValuesFormat(name=Disk), float_fd=DocValuesFormat(name=Lucene45)}, sim=DefaultSimilarity, locale=ja_JP_JP_#u-ca-japanese, timezone=Africa/Bujumbura
[junit4] 2> NOTE: Linux 3.8.0-34-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=92108152,total=152240128
[junit4] 2> NOTE: All tests run in this JVM: [TimeZoneUtilsTest, HighlighterConfigTest, RequestHandlersTest, TestCoreDiscovery, TestSolrQueryParserDefaultOperatorResource, DistributedSuggestComponentTest, TestStressLucene, ChaosMonkeyNothingIsSafeTest, SuggesterWFSTTest, DebugComponentTest, TestArbitraryIndexDir, TestUpdate, TestSolr4Spatial, ShardSplitTest, TestDistributedGrouping, TestDFRSimilarityFactory, QueryResultKeyTest, DocValuesMultiTest, TermVectorComponentDistributedTest, MinimalSchemaTest, RequiredFieldsTest, TestAnalyzedSuggestions, TestUniqueKeyFieldResource, SpellingQueryConverterTest, DocumentAnalysisRequestHandlerTest, TestRandomFaceting, DefaultValueUpdateProcessorTest, ExpressionTest]
[junit4] Completed on J1 in 1.28s, 12 tests, 3 failures <<< FAILURES!
[...truncated 477 lines...]
[junit4] Suite: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest
[junit4] 2> 555698 T1436 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1388193341445
[junit4] 2> 555699 T1436 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 555699 T1436 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 555700 T1436 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 555733 T1436 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 555774 T1436 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 555775 T1436 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 555780 T1436 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 556064 T1436 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 556067 T1436 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 556069 T1436 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 556073 T1436 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 556077 T1436 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 556080 T1436 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 556080 T1436 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 556080 T1436 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 556087 T1436 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 556087 T1436 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 556087 T1436 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 556123 T1436 oasc.CoreContainer.<init> New CoreContainer 664517164
[junit4] 2> 556123 T1436 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 556124 T1436 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 556125 T1436 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 556125 T1436 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 556125 T1436 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 556125 T1436 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 556126 T1436 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 556126 T1436 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 556126 T1436 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 556127 T1436 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 556130 T1436 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 556130 T1436 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 556130 T1436 oasc.CoreContainer.load Host Name:
[junit4] 2> 556134 T1437 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 556134 T1437 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 556135 T1437 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 556135 T1437 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 556168 T1437 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 556209 T1437 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 556209 T1437 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 556215 T1437 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 556496 T1437 oass.IndexSchema.readSchema default search fi
[...truncated too long message...]
6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f14d1c0a)),segFN=segments_10,generation=36}
[junit4] 2> 557525 T1436 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 36
[junit4] 2> 557526 T1436 oass.SolrIndexSearcher.<init> Opening Searcher@7b093058 main
[junit4] 2> 557526 T1436 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 557527 T1438 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7b093058 main{StandardDirectoryReader(segments_10:143:nrt _17(5.0):C2/1:delGen=1 _18(5.0):C1)}
[junit4] 2> 557528 T1436 oas.SolrTestCaseJ4.tearDown ###Ending testDupeDetection
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SignatureUpdateProcessorFactoryTest -Dtests.method=testDupeDetection -Dtests.seed=2D43837BA0607580 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sv -Dtests.timezone=America/Guyana -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.04s J1 | SignatureUpdateProcessorFactoryTest.testDupeDetection <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([2D43837BA0607580:767378B3A327D6FC]:0)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection(SignatureUpdateProcessorFactoryTest.java:119)
[junit4] > at java.lang.Thread.run(Thread.java:780)
[junit4] 2> 557534 T1436 oas.SolrTestCaseJ4.setUp ###Starting testFailNonIndexedSigWithOverwriteDupes
[junit4] 2> 557539 T1436 C759 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 557540 T1436 C759 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 557546 T1436 C759 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 557546 T1436 C759 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d66c45e6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f14d1c0a)),segFN=segments_10,generation=36}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d66c45e6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f14d1c0a)),segFN=segments_11,generation=37}
[junit4] 2> 557547 T1436 C759 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 37
[junit4] 2> 557548 T1436 C759 oass.SolrIndexSearcher.<init> Opening Searcher@34e58c8 main
[junit4] 2> 557548 T1436 C759 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 557550 T1438 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@34e58c8 main{StandardDirectoryReader(segments_11:144:nrt)}
[junit4] 2> 557550 T1436 C759 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 557551 T1436 oas.SolrTestCaseJ4.tearDown ###Ending testFailNonIndexedSigWithOverwriteDupes
[junit4] 2> 557553 T1436 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 557553 T1436 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=664517164
[junit4] 2> 557553 T1436 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@997ea2c5
[junit4] 2> 557562 T1436 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=42,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=253,cumulative_deletesById=0,cumulative_deletesByQuery=6,cumulative_errors=0}
[junit4] 2> 557563 T1436 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 557563 T1436 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 557563 T1436 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 557564 T1436 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 557564 T1436 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 557565 T1436 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1388193341445 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1388193341445;done=false>>]
[junit4] 2> 557565 T1436 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1388193341445
[junit4] 2> 557565 T1436 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1388193341445/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1388193341445/index;done=false>>]
[junit4] 2> 557566 T1436 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SignatureUpdateProcessorFactoryTest-1388193341445/index
[junit4] 2> NOTE: test params are: codec=Asserting, sim=DefaultSimilarity, locale=sv, timezone=America/Guyana
[junit4] 2> NOTE: Linux 3.8.0-34-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=88186512,total=204275712
[junit4] 2> NOTE: All tests run in this JVM: [TimeZoneUtilsTest, HighlighterConfigTest, RequestHandlersTest, TestCoreDiscovery, TestSolrQueryParserDefaultOperatorResource, DistributedSuggestComponentTest, TestStressLucene, ChaosMonkeyNothingIsSafeTest, SuggesterWFSTTest, DebugComponentTest, TestArbitraryIndexDir, TestUpdate, TestSolr4Spatial, ShardSplitTest, TestDistributedGrouping, TestDFRSimilarityFactory, QueryResultKeyTest, DocValuesMultiTest, TermVectorComponentDistributedTest, MinimalSchemaTest, RequiredFieldsTest, TestAnalyzedSuggestions, TestUniqueKeyFieldResource, SpellingQueryConverterTest, DocumentAnalysisRequestHandlerTest, TestRandomFaceting, DefaultValueUpdateProcessorTest, ExpressionTest, TestMaxScoreQueryParser, TestDistributedSearch, TestFastLRUCache, AlternateDirectoryTest, UniqFieldsUpdateProcessorFactoryTest, SpellCheckCollatorTest, StatsComponentTest, HighlighterTest, DisMaxRequestHandlerTest, XmlUpdateRequestHandlerTest, TestSchemaSimilarityResource, BadIndexSchemaTest, TestCodecSupport, HdfsChaosMonkeySafeLeaderTest, CoreAdminCreateDiscoverTest, HdfsBasicDistributedZkTest, SolrXmlInZkTest, TriLevelCompositeIdRoutingTest, TestStressRecovery, NotRequiredUniqueKeyTest, DistributedDebugComponentTest, MultiTermTest, TestDistribDocBasedVersion, TestImplicitCoreProperties, ParsingFieldUpdateProcessorsTest, TestHashPartitioner, TestFoldingMultitermQuery, BasicZkTest, TestBinaryField, TestMultiCoreConfBootstrap, TestDefaultSimilarityFactory, TestCollationField, TestXIncludeConfig, DirectSolrSpellCheckerTest, TestCSVLoader, FieldAnalysisRequestHandlerTest, PreAnalyzedFieldTest, TestComponentsName, TestUtils, BlockCacheTest, TestZkChroot, TestQueryTypes, TestRecovery, SolrCmdDistributorTest, IndexBasedSpellCheckerTest, IndexSchemaRuntimeFieldTest, LeaderElectionIntegrationTest, DirectUpdateHandlerTest, ConvertedLegacyTest, JSONWriterTest, PingRequestHandlerTest, SuggesterTSTTest, OutputWriterTest, SortByFunctionTest, TestSolrDeletionPolicy2, TestFieldTypeCollectionResource, TestBinaryResponseWriter, HardAutoCommitTest, TestFiltering, ShardRoutingTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, ZkSolrClientTest, ShardRoutingCustomTest, SliceStateUpdateTest, TestFaceting, TestRealTimeGet, TestReload, TestCoreContainer, TestGroupingSearch, SignatureUpdateProcessorFactoryTest]
[junit4] Completed on J1 in 1.89s, 6 tests, 1 failure <<< FAILURES!
[...truncated 441 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:453: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:446: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:491: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1304: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:937: There were test failures: 357 suites, 1573 tests, 4 failures, 67 ignored (6 assumptions)
Total time: 33 minutes 14 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure