Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/214/
3 tests failed.
FAILED: org.apache.solr.search.stats.TestExactStatsCache.test
Error Message:
unable to create new native thread
Stack Trace:
java.lang.OutOfMemoryError: unable to create new native thread
at java.lang.Thread.start0(Native Method)
at java.lang.Thread.start(Thread.java:717)
at
org.apache.http.impl.client.IdleConnectionEvictor.start(IdleConnectionEvictor.java:96)
at
org.apache.http.impl.client.HttpClientBuilder.build(HttpClientBuilder.java:1219)
at
org.apache.solr.client.solrj.impl.HttpClientUtil.createClient(HttpClientUtil.java:287)
at
org.apache.solr.handler.component.HttpShardHandlerFactory.init(HttpShardHandlerFactory.java:209)
at
org.apache.solr.handler.component.ShardHandlerFactory.newInstance(ShardHandlerFactory.java:47)
at org.apache.solr.core.CoreContainer.load(CoreContainer.java:503)
at
org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:263)
at
org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:183)
at
org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:139)
at
org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:873)
at
org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1596)
at
org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1659)
at
org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1316)
at
org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1145)
at
org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:448)
at
org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:306)
at
org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179)
at
org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69)
at
org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:394)
at
org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:367)
at
org.apache.solr.BaseDistributedSearchTestCase.createJetty(BaseDistributedSearchTestCase.java:424)
at
org.apache.solr.BaseDistributedSearchTestCase.createJetty(BaseDistributedSearchTestCase.java:396)
at
org.apache.solr.BaseDistributedSearchTestCase.createServers(BaseDistributedSearchTestCase.java:339)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1017)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED:
junit.framework.TestSuite.org.apache.solr.search.stats.TestExactStatsCache
Error Message:
9 threads leaked from SUITE scope at
org.apache.solr.search.stats.TestExactStatsCache: 1) Thread[id=1317,
name=qtp1126374514-1317, state=TIMED_WAITING, group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748) 2) Thread[id=1316,
name=qtp1126374514-1316, state=TIMED_WAITING, group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748) 3) Thread[id=1311,
name=qtp1126374514-1311, state=RUNNABLE, group=TGRP-TestExactStatsCache]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at
sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at
sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at
sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243)
at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:100)
at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:147)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
at java.lang.Thread.run(Thread.java:748) 4) Thread[id=1312,
name=qtp1126374514-1312, state=RUNNABLE, group=TGRP-TestExactStatsCache]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at
sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at
sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at
sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243)
at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:100)
at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:147)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
at java.lang.Thread.run(Thread.java:748) 5) Thread[id=1315,
name=qtp1126374514-1315, state=TIMED_WAITING, group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748) 6) Thread[id=1318,
name=qtp1126374514-1318, state=TIMED_WAITING, group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748) 7) Thread[id=1313,
name=qtp1126374514-1313-acceptor-0@51225b9d-ServerConnector@7e4cbe3{HTTP/1.1,[http/1.1]}{127.0.0.1:40320},
state=RUNNABLE, group=TGRP-TestExactStatsCache] at
sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method) at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:422)
at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:250)
at
org.eclipse.jetty.server.ServerConnector.accept(ServerConnector.java:371)
at
org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:601)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
at java.lang.Thread.run(Thread.java:748) 8) Thread[id=1314,
name=qtp1126374514-1314, state=TIMED_WAITING, group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748) 9) Thread[id=1319,
name=org.eclipse.jetty.server.session.HashSessionManager@731b5bb0Timer,
state=TIMED_WAITING, group=TGRP-TestExactStatsCache] at
sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 9 threads leaked from SUITE
scope at org.apache.solr.search.stats.TestExactStatsCache:
1) Thread[id=1317, name=qtp1126374514-1317, state=TIMED_WAITING,
group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
2) Thread[id=1316, name=qtp1126374514-1316, state=TIMED_WAITING,
group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
3) Thread[id=1311, name=qtp1126374514-1311, state=RUNNABLE,
group=TGRP-TestExactStatsCache]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243)
at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:100)
at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:147)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
at java.lang.Thread.run(Thread.java:748)
4) Thread[id=1312, name=qtp1126374514-1312, state=RUNNABLE,
group=TGRP-TestExactStatsCache]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243)
at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:100)
at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:147)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
at java.lang.Thread.run(Thread.java:748)
5) Thread[id=1315, name=qtp1126374514-1315, state=TIMED_WAITING,
group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
6) Thread[id=1318, name=qtp1126374514-1318, state=TIMED_WAITING,
group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
7) Thread[id=1313,
name=qtp1126374514-1313-acceptor-0@51225b9d-ServerConnector@7e4cbe3{HTTP/1.1,[http/1.1]}{127.0.0.1:40320},
state=RUNNABLE, group=TGRP-TestExactStatsCache]
at sun.nio.ch.ServerSocketChannelImpl.accept0(Native Method)
at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:422)
at
sun.nio.ch.ServerSocketChannelImpl.accept(ServerSocketChannelImpl.java:250)
at
org.eclipse.jetty.server.ServerConnector.accept(ServerConnector.java:371)
at
org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:601)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
at java.lang.Thread.run(Thread.java:748)
8) Thread[id=1314, name=qtp1126374514-1314, state=TIMED_WAITING,
group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
org.eclipse.jetty.util.BlockingArrayQueue.poll(BlockingArrayQueue.java:392)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.idleJobPoll(QueuedThreadPool.java:563)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.access$800(QueuedThreadPool.java:48)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
9) Thread[id=1319,
name=org.eclipse.jetty.server.session.HashSessionManager@731b5bb0Timer,
state=TIMED_WAITING, group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([BB0A89E88BEB03DE]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.search.stats.TestExactStatsCache
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=1311, name=qtp1126374514-1311, state=RUNNABLE,
group=TGRP-TestExactStatsCache] at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:189)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:100)
at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:147)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
at java.lang.Thread.run(Thread.java:748) 2) Thread[id=1312,
name=qtp1126374514-1312, state=RUNNABLE, group=TGRP-TestExactStatsCache]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at
sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at
sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at
sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at
sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243)
at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:100)
at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:147)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
at java.lang.Thread.run(Thread.java:748) 3) Thread[id=1319,
name=org.eclipse.jetty.server.session.HashSessionManager@731b5bb0Timer,
state=TIMED_WAITING, group=TGRP-TestExactStatsCache] at
sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=1311, name=qtp1126374514-1311, state=RUNNABLE,
group=TGRP-TestExactStatsCache]
at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:189)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:100)
at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:147)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
at java.lang.Thread.run(Thread.java:748)
2) Thread[id=1312, name=qtp1126374514-1312, state=RUNNABLE,
group=TGRP-TestExactStatsCache]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.select(ManagedSelector.java:243)
at
org.eclipse.jetty.io.ManagedSelector$SelectorProducer.produce(ManagedSelector.java:191)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:249)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:100)
at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:147)
at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
at java.lang.Thread.run(Thread.java:748)
3) Thread[id=1319,
name=org.eclipse.jetty.server.session.HashSessionManager@731b5bb0Timer,
state=TIMED_WAITING, group=TGRP-TestExactStatsCache]
at sun.misc.Unsafe.park(Native Method)
at
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
at
java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([BB0A89E88BEB03DE]:0)
Build Log:
[...truncated 11693 lines...]
[junit4] JVM J0: stdout was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171103_020906_7393983195436624194837.sysout
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] #
[junit4] # There is insufficient memory for the Java Runtime Environment to
continue.
[junit4] # Native memory allocation (mmap) failed to map 65536 bytes for
committing reserved memory.
[junit4] # An error report file with more information is saved as:
[junit4] #
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/hs_err_pid29331.log
[junit4] [thread 140128925939456 also had an error]
[junit4] #
[junit4] # Compiler replay data is saved as:
[junit4] #
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/replay_pid29331.log
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J0: stderr was not empty, see:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171103_020906_7396662746042090703353.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) 64-Bit Server VM warning: INFO:
os::commit_memory(0x00007f72d7a90000, 65536, 1) failed; error='Cannot allocate
memory' (errno=12)
[junit4] <<< JVM J0: EOF ----
[junit4] Suite: org.apache.solr.search.stats.TestExactStatsCache
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/init-core-data-001
[junit4] 2> 541125 WARN
(SUITE-TestExactStatsCache-seed#[BB0A89E88BEB03DE]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 541125 INFO
(SUITE-TestExactStatsCache-seed#[BB0A89E88BEB03DE]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 541126 INFO
(SUITE-TestExactStatsCache-seed#[BB0A89E88BEB03DE]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 541126 INFO
(SUITE-TestExactStatsCache-seed#[BB0A89E88BEB03DE]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 541126 INFO
(SUITE-TestExactStatsCache-seed#[BB0A89E88BEB03DE]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property:
/pq_gm/or
[junit4] 2> 550533 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/control/cores/collection1
[junit4] 2> 550557 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ] o.e.j.s.Server
jetty-9.3.20.v20170531
[junit4] 2> 550562 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@593232f1{/pq_gm/or,null,AVAILABLE}
[junit4] 2> 550638 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@29a9e165{HTTP/1.1,[http/1.1]}{127.0.0.1:34073}
[junit4] 2> 550638 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ] o.e.j.s.Server
Started @571728ms
[junit4] 2> 550638 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/pq_gm/or,
hostPort=34073,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/control/cores}
[junit4] 2> 550639 ERROR
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 550639 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.2.0
[junit4] 2> 550639 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 550639 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 550639 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-03T02:18:38.990Z
[junit4] 2> 550639 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/control/solr.xml
[junit4] 2> 550679 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 550679 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 550693 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 552144 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb
[junit4] 2> 552376 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb
[junit4] 2> 552376 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb
[junit4] 2> 552436 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/control/cores
[junit4] 2> 552436 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 552504 INFO (coreLoadExecutor-531-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 552552 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 553593 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 554243 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 557432 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 559291 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from configset
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 559328 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb
[junit4] 2> 559328 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 559328 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 559357 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=33, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=97.3916015625,
floorSegmentMB=1.34765625, forceMergeDeletesPctAllowed=11.687262617966653,
segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.12179407403354228
[junit4] 2> 559533 WARN (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated
plugin/analysis class [solr.OldLuceneQParserPlugin]. Please consult
documentation how to replace it accordingly.
[junit4] 2> 560553 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 560553 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 560554 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 560554 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 560584 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=20, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=80.787109375,
floorSegmentMB=2.126953125, forceMergeDeletesPctAllowed=15.89409865613748,
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8419445963188487
[junit4] 2> 560607 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@69124a5c[collection1] main]
[junit4] 2> 560608 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 561783 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 563460 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 563495 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 563496 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 563496 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 563496 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 563499 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 563502 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 563507 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 563508 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 563508 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 563509 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@69124a5c[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 563552 INFO (coreLoadExecutor-531-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1583009530540720128
[junit4] 2> 569963 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 569966 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ] o.e.j.s.Server
jetty-9.3.20.v20170531
[junit4] 2> 570010 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1654b081{/pq_gm/or,null,AVAILABLE}
[junit4] 2> 570018 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@56156342{HTTP/1.1,[http/1.1]}{127.0.0.1:43572}
[junit4] 2> 570029 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ] o.e.j.s.Server
Started @591118ms
[junit4] 2> 570029 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/pq_gm/or,
hostPort=43572,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/shard0/cores}
[junit4] 2> 570029 ERROR
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 570035 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.2.0
[junit4] 2> 570035 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 570035 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 570036 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-03T02:18:58.387Z
[junit4] 2> 570036 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/shard0/solr.xml
[junit4] 2> 570922 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 570922 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 570923 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 571573 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb
[junit4] 2> 571614 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb
[junit4] 2> 571631 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb
[junit4] 2> 571652 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/shard0/cores
[junit4] 2> 571652 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 572092 INFO (coreLoadExecutor-542-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4
transient cores
[junit4] 2> 572260 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 572897 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 573040 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 575016 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 575418 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from configset
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1,
trusted=true
[junit4] 2> 575435 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb
[junit4] 2> 575435 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 575435 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 575439 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=33, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=97.3916015625,
floorSegmentMB=1.34765625, forceMergeDeletesPctAllowed=11.687262617966653,
segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.12179407403354228
[junit4] 2> 578142 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 578142 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 578414 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 578414 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 578427 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=20, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=80.787109375,
floorSegmentMB=2.126953125, forceMergeDeletesPctAllowed=15.89409865613748,
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8419445963188487
[junit4] 2> 578446 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@15bee927[collection1] main]
[junit4] 2> 578446 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 578511 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 579409 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 579419 INFO (coreLoadExecutor-542-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1583009547178475520
[junit4] 2> 579449 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 579452 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 579528 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 579528 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 579528 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker:
multipleFields
[junit4] 2> 579543 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 579563 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 579576 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 579596 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 579603 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 579605 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@15bee927[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 579676 INFO (qtp1299546893-1237) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/pq_gm/or
path=/update params={wt=javabin&version=2}{deleteByQuery=*:*
(-1583009547343101952)} 0 100
[junit4] 2> 579983 INFO (qtp300795294-1254) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/pq_gm/or
path=/update params={wt=javabin&version=2}{deleteByQuery=*:*
(-1583009547766726656)} 0 2
[junit4] 2> 580324 INFO (qtp1299546893-1238) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/pq_gm/or
path=/update params={wt=javabin&version=2}{add=[0 (1583009547770920960)]} 0 340
[junit4] 2> 580393 INFO (qtp300795294-1255) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/pq_gm/or
path=/update params={wt=javabin&version=2}{add=[0 (1583009548135825408)]} 0 61
[junit4] 2> 580394 INFO (qtp1299546893-1239) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583009548200837120,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 580394 INFO (qtp1299546893-1239) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4c53cd19
commitCommandVersion:1583009548200837120
[junit4] 2> 580528 INFO (qtp1299546893-1239) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@48555d10[collection1] main]
[junit4] 2> 580529 INFO (qtp1299546893-1239) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 580531 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@48555d10[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):c1)))}
[junit4] 2> 580531 INFO (qtp1299546893-1239) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/pq_gm/or
path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 137
[junit4] 2> 580549 INFO (qtp300795294-1256) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583009548363366400,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 580549 INFO (qtp300795294-1256) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@717a12d8
commitCommandVersion:1583009548363366400
[junit4] 2> 580659 INFO (qtp300795294-1256) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@436033ef[collection1] main]
[junit4] 2> 580659 INFO (qtp300795294-1256) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 580661 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@436033ef[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):c1)))}
[junit4] 2> 580661 INFO (qtp300795294-1256) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/pq_gm/or
path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 112
[junit4] 2> 580955 INFO (qtp1299546893-1240) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/pq_gm/or path=/select
params={q=a_t:one&fl=*,score&wt=javabin&version=2&debugQuery=true} hits=1
status=0 QTime=287
[junit4] 2> 581088 INFO (qtp300795294-1252) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/pq_gm/or path=/select
params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=*,score&shards.purpose=32768&shard.url=http://127.0.0.1:43572/pq_gm/or/collection1&rid=127.0.0.1-collection1-1509675549317-0&version=2&q=a_t:one&requestPurpose=GET_TERM_STATS&NOW=1509675549312&isShard=true&wt=javabin&debugQuery=false}
status=0 QTime=82
[junit4] 2> 581148 INFO (qtp300795294-1251) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/pq_gm/or path=/select
params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&org.apache.solr.stats.termStats=a_t:on,1,1&shard.url=http://127.0.0.1:43572/pq_gm/or/collection1&rid=127.0.0.1-collection1-1509675549317-0&rows=10&version=2&org.apache.solr.stats.colStats=a_t,1,1,3,3&q=a_t:one&requestPurpose=GET_TOP_IDS,SET_TERM_STATS&NOW=1509675549312&isShard=true&org.apache.solr.stats.terms=a_t:on&wt=javabin&debugQuery=false}
hits=1 status=0 QTime=50
[junit4] 2> 581182 INFO (qtp300795294-1254) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/pq_gm/or path=/select
params={df=text&distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=http://127.0.0.1:43572/pq_gm/or/collection1&rid=127.0.0.1-collection1-1509675549317-0&version=2&q=a_t:one&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1509675549312&ids=0&isShard=true&wt=javabin&debugQuery=true}
status=0 QTime=0
[junit4] 2> 581197 INFO (qtp300795294-1257) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/pq_gm/or path=/select
params={q=a_t:one&shards=http://127.0.0.1:43572/pq_gm/or/collection1&fl=*,score&wt=javabin&version=2&debugQuery=true}
rid=127.0.0.1-collection1-1509675549317-0 hits=1 status=0 QTime=236
[junit4] 1> ======================= Control Response
=======================
[junit4] 1>
{responseHeader={status=0,QTime=287},response={numFound=1,start=0,maxScore=0.57735026,docs=[SolrDocument{id=0,
range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0],
a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42,
timestamp=Fri Nov 03 03:19:08 CET 2017, _version_=1583009547770920960,
intDvoDefault=42,
score=0.57735026}]},debug={rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,explain={0=
[junit4] 1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity],
result of:
[junit4] 1> 0.57735026 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1
from:
[junit4] 1> 1.0 = docFreq
[junit4] 1> 1.0 = docCount
[junit4] 1> 0.57735026 = fieldNorm(doc=0)
[junit4] 1>
},QParser=LuceneQParser,timing={time=287.0,prepare={time=11.0,query={time=11.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=275.0,query={time=269.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=5.0}}}}}
[junit4] 1>
[junit4] 1>
[junit4] 1> ======================= Shard Response =======================
[junit4] 1>
[junit4] 1>
{responseHeader={status=0,QTime=236},response={numFound=1,start=0,maxScore=0.57735026,docs=[SolrDocument{id=0,
range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0],
a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42,
timestamp=Fri Nov 03 03:19:08 CET 2017, _version_=1583009548135825408,
intDvoDefault=42,
score=0.57735026}]},debug={track={rid=127.0.0.1-collection1-1509675549317-0,PARSE_QUERY={http://127.0.0.1:43572/pq_gm/or/collection1={QTime=82,ElapsedTime=116,RequestPurpose=GET_TERM_STATS,Response={responseHeader={status=0,QTime=82},org.apache.solr.stats.terms=a_t:on,org.apache.solr.stats.termStats=a_t:on,1,1,org.apache.solr.stats.colStats=a_t,1,1,3,3,debug={timing={time=82.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=81.0,query={time=81.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},EXECUTE_QUERY={http://127.0.0.1:43572/pq_gm/or/collection1={QTime=50,ElapsedTime=65,RequestPurpose=GET_TOP_IDS,SET_TERM_STATS,NumFound=1,Response={responseHeader={status=0,QTime=50},response={numFound=1,start=0,maxScore=0.57735026,docs=[SolrDocument{id=0,
score=0.57735026}]},sort_values={},debug={timing={time=50.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=50.0,query={time=50.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},GET_FIELDS={http://127.0.0.1:43572/pq_gm/or/collection1={QTime=0,ElapsedTime=15,RequestPurpose=GET_FIELDS,GET_DEBUG,NumFound=1,Response={responseHeader={status=0,QTime=0},response={numFound=1,start=0,docs=[SolrDocument{id=0,
range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0],
a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42,
timestamp=Fri Nov 03 03:19:08 CET 2017, _version_=1583009548135825408,
intDvoDefault=42,
score=0.57735026}]},debug={rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,explain={0=
[junit4] 1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity],
result of:
[junit4] 1> 0.57735026 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1
from:
[junit4] 1> 1.0 = docFreq
[junit4] 1> 1.0 = docCount
[junit4] 1> 0.57735026 = fieldNorm(doc=0)
[junit4] 1>
},QParser=LuceneQParser,timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}}},timing={time=132.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=131.0,query={time=131.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}},rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,QParser=LuceneQParser,explain={0=
[junit4] 1> 0.57735026 = weight(a_t:on in 0) [MockConfigurableSimilarity],
result of:
[junit4] 1> 0.57735026 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1
from:
[junit4] 1> 1.0 = docFreq
[junit4] 1> 1.0 = docCount
[junit4] 1> 0.57735026 = fieldNorm(doc=0)
[junit4] 1> }}}
[junit4] 2> 581244 INFO (qtp1299546893-1241) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/pq_gm/or
path=/update params={wt=javabin&version=2}{add=[1 (1583009549062766592)]} 0 27
[junit4] 2> 581268 INFO (qtp300795294-1258) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/pq_gm/or
path=/update params={wt=javabin&version=2}{add=[1 (1583009549093175296)]} 0 23
[junit4] 2> 581269 INFO (qtp1299546893-1234) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583009549118341120,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 581269 INFO (qtp1299546893-1234) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@4c53cd19
commitCommandVersion:1583009549118341120
[junit4] 2> 581318 INFO (qtp1299546893-1234) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4a24d2b6[collection1] main]
[junit4] 2> 581319 INFO (qtp1299546893-1234) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 581333 INFO
(searcherExecutor-532-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4a24d2b6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):c1)
Uninverting(_1(7.2.0):c1)))}
[junit4] 2> 581333 INFO (qtp1299546893-1234) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/pq_gm/or
path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 64
[junit4] 2> 581334 INFO (qtp300795294-1256) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1583009549186498560,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 581334 INFO (qtp300795294-1256) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@717a12d8
commitCommandVersion:1583009549186498560
[junit4] 2> 581408 INFO (qtp300795294-1256) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@86f358f[collection1] main]
[junit4] 2> 581411 INFO
(searcherExecutor-543-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@86f358f[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.2.0):c1)
Uninverting(_1(7.2.0):c1)))}
[junit4] 2> 581411 INFO (qtp300795294-1256) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 581411 INFO (qtp300795294-1256) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/pq_gm/or
path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 77
[junit4] 2> 581699 INFO (qtp1299546893-1237) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/pq_gm/or path=/select
params={q=a_t:one+a_t:four&fl=*,score&wt=javabin&version=2&debugQuery=true}
hits=2 status=0 QTime=287
[junit4] 2> 581703 INFO (qtp300795294-1255) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/pq_gm/or path=/select
params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=*,score&shards.purpose=32768&shard.url=http://127.0.0.1:43572/pq_gm/or/collection1&rid=127.0.0.1-collection1-1509675550052-1&version=2&q=a_t:one+a_t:four&requestPurpose=GET_TERM_STATS&NOW=1509675550052&isShard=true&wt=javabin&debugQuery=false}
status=0 QTime=0
[junit4] 2> 581706 INFO (qtp300795294-1255) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/pq_gm/or path=/select
params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&org.apache.solr.stats.termStats=a_t:on,2,2!a_t:four,1,1&shard.url=http://127.0.0.1:43572/pq_gm/or/collection1&rid=127.0.0.1-collection1-1509675550052-1&rows=10&version=2&org.apache.solr.stats.colStats=a_t,2,2,8,8&q=a_t:one+a_t:four&requestPurpose=GET_TOP_IDS,SET_TERM_STATS&NOW=1509675550052&isShard=true&org.apache.solr.stats.terms=a_t:on&org.apache.solr.stats.terms=a_t:four&wt=javabin&debugQuery=false}
hits=2 status=0 QTime=1
[junit4] 2> 581774 INFO (qtp300795294-1257) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/pq_gm/or path=/select
params={df=text&distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=http://127.0.0.1:43572/pq_gm/or/collection1&rid=127.0.0.1-collection1-1509675550052-1&version=2&q=a_t:one+a_t:four&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1509675550052&ids=0,1&isShard=true&wt=javabin&debugQuery=true}
status=0 QTime=66
[junit4] 2> 581777 INFO (qtp300795294-1252) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/pq_gm/or path=/select
params={q=a_t:one+a_t:four&shards=http://127.0.0.1:43572/pq_gm/or/collection1&fl=*,score&wt=javabin&version=2&debugQuery=true}
rid=127.0.0.1-collection1-1509675550052-1 hits=2 status=0 QTime=76
[junit4] 1> ======================= Control Response
=======================
[junit4] 1>
{responseHeader={status=0,QTime=287},response={numFound=2,start=0,maxScore=1.0757567,docs=[SolrDocument{id=1,
range_facet_l=[1], id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1],
a_t=[one two three four five], shard_i=[1], multiDefault=[muLti-Default],
intDefault=42, timestamp=Fri Nov 03 03:19:09 CET 2017,
_version_=1583009549062766592, intDvoDefault=42, score=1.0757567},
SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0],
range_facet_i_dv=[0], a_t=[one two three], shard_i=[1],
multiDefault=[muLti-Default], intDefault=42, timestamp=Fri Nov 03 03:19:08 CET
2017, _version_=1583009547770920960, intDvoDefault=42,
score=0.57735026}]},debug={rawquerystring=a_t:one a_t:four,querystring=a_t:one
a_t:four,parsedquery=a_t:on a_t:four,parsedquery_toString=a_t:on
a_t:four,explain={1=
[junit4] 1> 1.0757567 = sum of:
[junit4] 1> 0.4472136 = weight(a_t:on in 0)
[MockConfigurableSimilarity], result of:
[junit4] 1> 0.4472136 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1
from:
[junit4] 1> 2.0 = docFreq
[junit4] 1> 2.0 = docCount
[junit4] 1> 0.4472136 = fieldNorm(doc=0)
[junit4] 1> 0.6285431 = weight(a_t:four in 0)
[MockConfigurableSimilarity], result of:
[junit4] 1> 0.6285431 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.4054651 = idf, computed as
log((docCount+1)/(docFreq+1)) + 1 from:
[junit4] 1> 1.0 = docFreq
[junit4] 1> 2.0 = docCount
[junit4] 1> 0.4472136 = fieldNorm(doc=0)
[junit4] 1> ,0=
[junit4] 1> 0.57735026 = sum of:
[junit4] 1> 0.57735026 = weight(a_t:on in 0)
[MockConfigurableSimilarity], result of:
[junit4] 1> 0.57735026 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1
from:
[junit4] 1> 2.0 = docFreq
[junit4] 1> 2.0 = docCount
[junit4] 1> 0.57735026 = fieldNorm(doc=0)
[junit4] 1>
},QParser=LuceneQParser,timing={time=287.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=286.0,query={time=93.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=193.0}}}}}
[junit4] 1>
[junit4] 1>
[junit4] 1> ======================= Shard Response =======================
[junit4] 1>
[junit4] 1>
{responseHeader={status=0,QTime=76},response={numFound=2,start=0,maxScore=1.0757567,docs=[SolrDocument{id=1,
range_facet_l=[1], id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1],
a_t=[one two three four five], shard_i=[1], multiDefault=[muLti-Default],
intDefault=42, timestamp=Fri Nov 03 03:19:09 CET 2017,
_version_=1583009549093175296, intDvoDefault=42, score=1.0757567},
SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0],
range_facet_i_dv=[0], a_t=[one two three], shard_i=[1],
multiDefault=[muLti-Default], intDefault=42, timestamp=Fri Nov 03 03:19:08 CET
2017, _version_=1583009548135825408, intDvoDefault=42,
score=0.57735026}]},debug={track={rid=127.0.0.1-collection1-1509675550052-1,PARSE_QUERY={http://127.0.0.1:43572/pq_gm/or/collection1={QTime=0,ElapsedTime=1,RequestPurpose=GET_TERM_STATS,Response={responseHeader={status=0,QTime=0},org.apache.solr.stats.terms=a_t:on,org.apache.solr.stats.terms=a_t:four,org.apache.solr.stats.termStats=a_t:on,2,2!a_t:four,1,1,org.apache.solr.stats.colStats=a_t,2,2,8,8,debug={timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},EXECUTE_QUERY={http://127.0.0.1:43572/pq_gm/or/collection1={QTime=1,ElapsedTime=2,RequestPurpose=GET_TOP_IDS,SET_TERM_STATS,NumFound=2,Response={responseHeader={status=0,QTime=1},response={numFound=2,start=0,maxScore=1.0757567,docs=[SolrDocument{id=1,
score=1.0757567}, SolrDocument{id=0,
score=0.57735026}]},sort_values={},debug={timing={time=1.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=1.0,query={time=1.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},GET_FIELDS={http://127.0.0.1:43572/pq_gm/or/collection1={QTime=66,ElapsedTime=69,RequestPurpose=GET_FIELDS,GET_DEBUG,NumFound=2,Response={responseHeader={status=0,QTime=66},response={numFound=2,start=0,docs=[SolrDocument{id=0,
range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0],
a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42,
timestamp=Fri Nov 03 03:19:08 CET 2017, _version_=1583009548135825408,
intDvoDefault=42, score=0.57735026}, SolrDocument{id=1, range_facet_l=[1],
id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1], a_t=[one two three four
five], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Fri
Nov 03 03:19:09 CET 2017, _version_=1583009549093175296, intDvoDefault=42,
score=1.0757567}]},debug={rawquerystring=a_t:one a_t:four,querystring=a_t:one
a_t:four,parsedquery=a_t:on a_t:four,parsedquery_toString=a_t:on
a_t:four,explain={0=
[junit4] 1> 0.57735026 = sum of:
[junit4] 1> 0.57735026 = weight(a_t:on in 0)
[MockConfigurableSimilarity], result of:
[junit4] 1> 0.57735026 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1
from:
[junit4] 1> 2.0 = docFreq
[junit4] 1> 2.0 = docCount
[junit4] 1> 0.57735026 = fieldNorm(doc=0)
[junit4] 1> ,1=
[junit4] 1> 1.0757567 = sum of:
[junit4] 1> 0.4472136 = weight(a_t:on in 0)
[MockConfigurableSimilarity], result of:
[junit4] 1> 0.4472136 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1
from:
[junit4] 1> 2.0 = docFreq
[junit4] 1> 2.0 = docCount
[junit4] 1> 0.4472136 = fieldNorm(doc=0)
[junit4] 1> 0.6285431 = weight(a_t:four in 0)
[MockConfigurableSimilarity], result of:
[junit4] 1> 0.6285431 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.4054651 = idf, computed as
log((docCount+1)/(docFreq+1)) + 1 from:
[junit4] 1> 1.0 = docFreq
[junit4] 1> 2.0 = docCount
[junit4] 1> 0.4472136 = fieldNorm(doc=0)
[junit4] 1>
},QParser=LuceneQParser,timing={time=66.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=66.0,query={time=65.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}}},timing={time=67.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=67.0,query={time=66.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}},rawquerystring=a_t:one
a_t:four,querystring=a_t:one a_t:four,parsedquery=a_t:on
a_t:four,parsedquery_toString=a_t:on a_t:four,QParser=LuceneQParser,explain={1=
[junit4] 1> 1.0757567 = sum of:
[junit4] 1> 0.4472136 = weight(a_t:on in 0)
[MockConfigurableSimilarity], result of:
[junit4] 1> 0.4472136 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1
from:
[junit4] 1> 2.0 = docFreq
[junit4] 1> 2.0 = docCount
[junit4] 1> 0.4472136 = fieldNorm(doc=0)
[junit4] 1> 0.6285431 = weight(a_t:four in 0)
[MockConfigurableSimilarity], result of:
[junit4] 1> 0.6285431 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.4054651 = idf, computed as
log((docCount+1)/(docFreq+1)) + 1 from:
[junit4] 1> 1.0 = docFreq
[junit4] 1> 2.0 = docCount
[junit4] 1> 0.4472136 = fieldNorm(doc=0)
[junit4] 1> ,0=
[junit4] 1> 0.57735026 = sum of:
[junit4] 1> 0.57735026 = weight(a_t:on in 0)
[MockConfigurableSimilarity], result of:
[junit4] 1> 0.57735026 = fieldWeight in 0, product of:
[junit4] 1> 1.0 = tf(freq=1.0), with freq of:
[junit4] 1> 1.0 = termFreq=1.0
[junit4] 1> 1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1
from:
[junit4] 1> 2.0 = docFreq
[junit4] 1> 2.0 = docCount
[junit4] 1> 0.57735026 = fieldNorm(doc=0)
[junit4] 1> }}}
[junit4] 2> 581793 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 581823 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@29a9e165{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 581824 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=63749778
[junit4] 2> 581824 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 581824 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@20799dc7: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@1c1f6872
[junit4] 2> 581860 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 581860 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@539ac22: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@3341124a
[junit4] 2> 581862 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 581862 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@77208c34: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@2af1c412
[junit4] 2> 581928 INFO (coreCloseExecutor-548-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@115c902a
[junit4] 2> 581929 INFO (coreCloseExecutor-548-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=291278890
[junit4] 2> 581929 INFO (coreCloseExecutor-548-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6991a031: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@60cb3be2
[junit4] 2> 582056 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@593232f1{/pq_gm/or,null,UNAVAILABLE}
[junit4] 2> 582121 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@56156342{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 582121 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1434922796
[junit4] 2> 582121 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 582121 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@3286e9a7: rootName = null,
domain = solr.node, service url = null, agent id = null] for registry solr.node
/ com.codahale.metrics.MetricRegistry@4155bc0
[junit4] 2> 582134 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 582134 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1d07e4c: rootName = null,
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm /
com.codahale.metrics.MetricRegistry@3341124a
[junit4] 2> 582166 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 582166 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@778874e4: rootName = null,
domain = solr.jetty, service url = null, agent id = null] for registry
solr.jetty / com.codahale.metrics.MetricRegistry@2af1c412
[junit4] 2> 582182 INFO (coreCloseExecutor-550-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@4e98ea2d
[junit4] 2> 582182 INFO (coreCloseExecutor-550-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1, tag=1318644269
[junit4] 2> 582414 INFO (coreCloseExecutor-550-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@10639b8f: rootName = null,
domain = solr.core.collection1, service url = null, agent id = null] for
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@62666a00
[junit4] 2> 582834 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1654b081{/pq_gm/or,null,UNAVAILABLE}
[junit4] 2> 586128 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ] o.e.j.s.Server
jetty-9.3.20.v20170531
[junit4] 2> 586174 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@63562dfc{/pq_gm/or,null,AVAILABLE}
[junit4] 2> 586176 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@51516f14{HTTP/1.1,[http/1.1]}{127.0.0.1:45431}
[junit4] 2> 586180 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ] o.e.j.s.Server
Started @607269ms
[junit4] 2> 586180 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/pq_gm/or,
hostPort=45431,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/control/cores}
[junit4] 2> 586180 ERROR
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 586181 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.2.0
[junit4] 2> 586181 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 586181 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 586181 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-11-03T02:19:14.532Z
[junit4] 2> 586181 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_BB0A89E88BEB03DE-001/tempDir-001/control/solr.xml
[junit4] 2> 586204 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay
is ignored
[junit4] 2> 586204 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig Configuration parameter
autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 586206 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.c.SolrXmlConfig MBean server found:
com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb, but no JMX reporters were
configured - adding default JMX reporter.
[junit4] 2> 586440 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node')
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@409657cb
[junit4] 2> 586490 INFO
(TEST-TestExactStatsCache.test-seed#[BB0A89E88BEB03DE]) [ ]
o.a.s.m.r.SolrJmxReporter JMX m
[...truncated too long message...]
ave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptor-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-admin-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/byte-buddy-1.6.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/commons-math3-3.6.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/hadoop-common-2.7.4-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/hadoop-hdfs-2.7.4-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/hadoop-minikdc-2.7.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/htrace-core-3.2.0-incubating.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/jetty-sslengine-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/mockito-core-2.6.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/netty-all-4.0.36.Final.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/test-lib/objenesis-2.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/build/analysis/icu/lucene-analyzers-icu-7.2.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/build/backward-codecs/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/contrib/analysis-extras/lib/icu4j-59.1.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.4.0.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-log4j.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-junit4.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jai.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-javamail.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bsf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-net.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-antlr.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jsch.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-oro.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-commons-logging.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-netrexx.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-testutil.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jdepend.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-bcel.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-xalan2.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-resolver.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-jmf.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-apache-regexp.jar:/home/jenkins/tools/ant/apache-ant-1.8.4/lib/ant-swing.jar:/usr/local/asfpackages/java/jdk1.8.0_144/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.5.3.jar
com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171103_020906_7398136609805992474478.events
@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171103_020906_7393876332395242502189.suites
-stdin
[junit4] ERROR: JVM J0 ended with an exception: Forked process returned with
error code: 1. Very likely a JVM crash. See process stdout at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171103_020906_7393983195436624194837.sysout
See process stderr at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171103_020906_7396662746042090703353.syserr
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1519)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:126)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:982)
[junit4] at
com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:979)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] at java.lang.Thread.run(Thread.java:748)
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:826: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:770: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:59: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build.xml:262:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/common-build.xml:556:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/common-build.xml:1505:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/common-build.xml:1032:
At least one slave process threw an exception, first: Forked process returned
with error code: 1. Very likely a JVM crash. See process stdout at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171103_020906_7393983195436624194837.sysout
See process stderr at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/temp/junit4-J0-20171103_020906_7396662746042090703353.syserr
Total time: 208 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[Fast Archiver] No artifacts from Lucene-Solr-Tests-7.x #203 to compare, so
performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
