You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/10/25 16:03:11 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 3626
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3626/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
7 tests failed.
FAILED: org.apache.solr.cloud.RecoveryZkTest.test
Error Message:
There are still nodes recoverying - waited for 120 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 120 seconds
at __randomizedtesting.SeedInfo.seed([6E3F85D9190C0879:E66BBA03B7F06581]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:184)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:862)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1418)
at org.apache.solr.cloud.RecoveryZkTest.test(RecoveryZkTest.java:105)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=STREAM}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([6E3F85D9190C0879:B2FF2CAF748DACB3]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=ENUM}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([6E3F85D9190C0879:B2FF2CAF748DACB3]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=DV}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([6E3F85D9190C0879:B2FF2CAF748DACB3]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=DVHASH}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([6E3F85D9190C0879:B2FF2CAF748DACB3]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=UIF}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([6E3F85D9190C0879:B2FF2CAF748DACB3]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=SMART}
Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
at __randomizedtesting.SeedInfo.seed([6E3F85D9190C0879:B2FF2CAF748DACB3]:0)
at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11099 lines...]
[junit4] Suite: org.apache.solr.search.facet.TestJsonFacets
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets_6E3F85D9190C0879-001/init-core-data-001
[junit4] 2> 893691 INFO (SUITE-TestJsonFacets-seed#[6E3F85D9190C0879]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 893754 INFO (SUITE-TestJsonFacets-seed#[6E3F85D9190C0879]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 893755 INFO (SUITE-TestJsonFacets-seed#[6E3F85D9190C0879]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 893803 INFO (SUITE-TestJsonFacets-seed#[6E3F85D9190C0879]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 893816 INFO (SUITE-TestJsonFacets-seed#[6E3F85D9190C0879]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=example
[junit4] 2> 893860 INFO (SUITE-TestJsonFacets-seed#[6E3F85D9190C0879]-worker) [ ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 893873 INFO (coreLoadExecutor-3498-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 893892 INFO (coreLoadExecutor-3498-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 893902 INFO (coreLoadExecutor-3498-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 893960 INFO (coreLoadExecutor-3498-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 893970 INFO (coreLoadExecutor-3498-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1
[junit4] 2> 893970 INFO (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.search.facet.TestJsonFacets_6E3F85D9190C0879-001/init-core-data-001/]
[junit4] 2> 893970 INFO (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@49332ad5
[junit4] 2> 893971 INFO (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=25, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 893975 WARN (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 893985 INFO (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 893985 INFO (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 893986 INFO (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 893986 INFO (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 893986 INFO (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=35, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=25.6376953125, floorSegmentMB=1.8701171875, forceMergeDeletesPctAllowed=6.104472937717389, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 893986 INFO (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5081a923[collection1] main]
[junit4] 2> 893987 WARN (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 893987 INFO (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 893988 INFO (searcherExecutor-3499-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5081a923[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 893988 INFO (coreLoadExecutor-3498-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549174132625637376
[junit4] 2> 893991 INFO (SUITE-TestJsonFacets-seed#[6E3F85D9190C0879]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 893994 INFO (TEST-TestJsonFacets.testErrors-seed#[6E3F85D9190C0879]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testErrors {p0=STREAM}
[junit4] 2> 893998 INFO (TEST-TestJsonFacets.testErrors-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1549174132634025984)} 0 2
[junit4] 2> 894019 INFO (TEST-TestJsonFacets.testErrors-seed#[6E3F85D9190C0879]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching ignore_exception
[junit4] 2> 894020 INFO (TEST-TestJsonFacets.testErrors-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={ignore_exception=true&q=*:*&json.facet={f:{type:ignore_exception_aaa,+field:bbbbbb}}&wt=json&indent=true} status=400 QTime=19
[junit4] 2> 894020 INFO (TEST-TestJsonFacets.testErrors-seed#[6E3F85D9190C0879]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testErrors {p0=STREAM}
[junit4] 2> 894022 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStats {p0=STREAM}
[junit4] 2> 894024 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1549174132663386112)} 0 0
[junit4] 2> 894027 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1 (1549174132664434688)]} 0 2
[junit4] 2> 894028 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1 (1549174132666531840)]} 0 0
[junit4] 2> 894028 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1 (1549174132667580416)]} 0 0
[junit4] 2> 894029 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2 (1549174132668628992)]} 0 0
[junit4] 2> 894029 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3 (1549174132668628993)]} 0 0
[junit4] 2> 894029 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 894030 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2c6b78d6
[junit4] 2> 894040 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@41d0125c[collection1] main]
[junit4] 2> 894041 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 894042 INFO (searcherExecutor-3499-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@41d0125c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C5/2:delGen=1)))}
[junit4] 2> 894042 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 13
[junit4] 2> 894045 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4 (1549174132683309056)]} 0 1
[junit4] 2> 894046 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5 (1549174132685406208)]} 0 0
[junit4] 2> 894046 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 894046 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2c6b78d6
[junit4] 2> 894049 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@532ea7ed[collection1] main]
[junit4] 2> 894049 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 894050 INFO (searcherExecutor-3499-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@532ea7ed[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C5/2:delGen=1) Uninverting(_1(7.0.0):C2)))}
[junit4] 2> 894051 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 894052 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[6 (1549174132691697664)]} 0 1
[junit4] 2> 894053 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 894053 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2c6b78d6
[junit4] 2> 894055 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1502f94d[collection1] main]
[junit4] 2> 894056 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 894057 INFO (searcherExecutor-3499-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1502f94d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C5/2:delGen=1) Uninverting(_1(7.0.0):C2) Uninverting(_2(7.0.0):C1)))}
[junit4] 2> 894057 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 894079 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={catA:{query:{q:'${cat_s}:A'}},++catA2:{query:{query:'${cat_s}:A'}},++catA3:{query:'${cat_s}:A'}++++}&wt=json&indent=true} hits=6 status=0 QTime=21
[junit4] 2> 894085 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={catA:{query:{q:'${cat_s}:A'}},++catA2:{query:{query:'${cat_s}:A'}},++catA3:{query:'${cat_s}:A'}++++}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894088 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={+catB:{type:query,+q:'${cat_s}:B',+facet:{nj:{query:'${where_s}:NJ'},+ny:{query:'${where_s}:NY'}}+}}&wt=json&indent=true} hits=6 status=0 QTime=2
[junit4] 2> 894089 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=id:(2+3)&json.facet={+catB:{query:{q:'${cat_s}:B',+facet:{nj:{query:'${where_s}:NJ'},+ny:{query:'${where_s}:NY'}}+}}}&wt=json&indent=true} hits=2 status=0 QTime=1
[junit4] 2> 894090 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={+catB:{query:{q:'${cat_s}:B',+facet:{nj:{query:{q:'${where_s}:NJ'}},+ny:{query:'${where_s}:NY'}}+}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894143 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={c1:{field:'${cat_s}'},+c2:{field:{field:'${cat_s}'}},+c3:{${terms}+type:terms,+field:'${cat_s}'}++}&wt=json&indent=true} hits=6 status=0 QTime=52
[junit4] 2> 894145 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:'${cat_s}',+mincount:3}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894146 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=id:1&json.facet={f1:{terms:'${cat_s}'}}&wt=json&indent=true} hits=1 status=0 QTime=0
[junit4] 2> 894146 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=id:1&json.facet={processEmpty:true,+f1:{terms:{${terms}+field:'${cat_s}',+mincount:0}}}&wt=json&indent=true} hits=1 status=0 QTime=0
[junit4] 2> 894173 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=id:1&json.facet={processEmpty:true,+f1:{terms:{${terms}+field:'${cat_s}',+mincount:0,+allBuckets:true,+facet:{n1:'sum(${num_d})'}++}}}&wt=json&indent=true} hits=1 status=0 QTime=25
[junit4] 2> 894174 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:'${cat_s}',+sort:'n1+desc',+facet:{n1:'sum(${num_d})'}++}}+,+f2:{terms:{${terms}+field:'${cat_s}',+sort:'n1+asc',+facet:{n1:'sum(${num_d})'}++}}+}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894218 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{${terms}+type:terms,+field:'${cat_s}',+sort:'x+desc',+facet:{x:'min(${num_d})'}++}+,+f2:{${terms}+type:terms,+field:'${cat_s}',+sort:'x+desc',+facet:{x:'max(${num_d})'}++}++,+f3:{${terms}+type:terms,+field:'${cat_s}',+sort:'x+desc',+facet:{x:'unique(${where_s})'}++}++,+f4:{${terms}+type:terms,+field:'${cat_s}',+sort:'x+desc',+facet:{x:'hll(${where_s})'}++}+}&wt=json&indent=true} hits=6 status=0 QTime=43
[junit4] 2> 894228 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:'${cat_s}',+sort:'n1+desc',+facet:{n1:'avg(add(${num_d},${num_d}))'}++}}+,+f2:{terms:{${terms}+field:'${cat_s}',+sort:'n1+asc',+facet:{n1:'avg(add(${num_d},${num_d}))'}++}}+}&wt=json&indent=true} hits=6 status=0 QTime=7
[junit4] 2> 894236 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:'${cat_s}',+sort:'n1+desc',+facet:{n1:'percentile(${num_d},50)'}++}}+,+f2:{terms:{${terms}+field:'${cat_s}',+sort:'n1+asc',+facet:{n1:'percentile(${num_d},50)'}++}}+}&wt=json&indent=true} hits=6 status=0 QTime=7
[junit4] 2> 894237 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:${cat_s},+sort:'n1+desc',+facet:{n1:'percentile(${num_d},50,0,100)'}++}}+,+f2:{terms:{${terms}+field:${cat_s},+sort:'n1+asc',+facet:{n1:'percentile(${num_d},50,0,100)'}++}}+}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894244 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:'${cat_s}',+sort:'count+desc'+}++}+++++++++++,+f2:{terms:{${terms}+field:'${cat_s}',+sort:'count+asc'++}++}+++++++++++,+f3:{terms:{${terms}+field:'${cat_s}',+sort:'index+asc'++}++}+++++++++++,+f4:{terms:{${terms}+field:'${cat_s}',+sort:'index+desc'+}++}}&wt=json&indent=true} hits=6 status=0 QTime=6
[junit4] 2> 894250 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:'${cat_s}',+sort:'count'+}++}+++++++++++,+f2:{terms:{${terms}+field:'${cat_s}',+sort:'count+asc'++}++}+++++++++++,+f3:{terms:{${terms}+field:'${cat_s}',+sort:'index'++}++}+++++++++++,+f4:{terms:{${terms}+field:'${cat_s}',+sort:'index+desc'+}++}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894252 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=id:1+id:6&json.facet={f1:{terms:{${terms}+field:'${cat_s}',+sort:'count+desc'+}++}+++++++++++,+f2:{terms:{${terms}+field:'${cat_s}',+sort:'count+asc'++}++}}&wt=json&indent=true} hits=2 status=0 QTime=1
[junit4] 2> 894253 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={cat:{terms:{${terms}+field:'${cat_s}',+facet:{nj:{query:'${where_s}:NJ'}}++++}+++}}+}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894254 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=id:(2+5+4)&json.facet={cat:{terms:{${terms}+field:'${cat_s}',+facet:{nj:{query:'${where_s}:NJ'}}++++}+++}}+}&wt=json&indent=true} hits=3 status=0 QTime=0
[junit4] 2> 894255 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:${super_s},+prefix:s,+mincount:0+}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894255 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:${super_s},+prefix:ttt,+mincount:0+}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894256 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:${super_s},+prefix:aaaaaa,+mincount:0+}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894256 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:${super_s},+prefix:zzzzzz,+mincount:0+}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894257 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={+f1:{${terms}+type:terms,+field:${where_s},+prefix:N++},f2:{${terms}+type:terms,+field:${where_s},+prefix:NY+},f3:{${terms}+type:terms,+field:${where_s},+prefix:NJ+}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894272 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=multi_ss,memSize=4515,tindexSize=41,time=2,phase1=2,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 2> 894273 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={+f1:{${terms}+type:terms,+field:${multi_ss},+prefix:A++},f2:{${terms}+type:terms,+field:${multi_ss},+prefix:z+},f3:{${terms}+type:terms,+field:${multi_ss},+prefix:aa+},f4:{${terms}+type:terms,+field:${multi_ss},+prefix:bb+},f5:{${terms}+type:terms,+field:${multi_ss},+prefix:a+},f6:{${terms}+type:terms,+field:${multi_ss},+prefix:b+}}&wt=json&indent=true} hits=6 status=0 QTime=15
[junit4] 2> 894282 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:${noexist},+missing:true}}}&wt=json&indent=true} hits=6 status=0 QTime=9
[junit4] 2> 894284 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:${sparse_s},+missing:true+}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894285 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:${sparse_s},+missing:true,+facet:{x:'sum(${num_d})'}+++}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894285 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:${sparse_s},+missing:true,+prefix:on,+facet:{x:'sum(${num_d})'}+++}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894286 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f1:{terms:{${terms}+field:${sparse_s},+missing:true,+prefix:ppp,+facet:{x:'sum(${num_d})'}+++}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894288 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&facet=true&json.facet={f1:{terms:{${terms}+field:${cat_s},+numBuckets:true,+limit:1}}}&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 894288 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&facet=true&json.facet={f1:{terms:{${terms}+field:${cat_s},+numBuckets:true,+prefix:B}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894289 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&facet=true&json.facet={f1:{terms:{${terms}+field:${cat_s},+numBuckets:true,+mincount:3}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894323 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f:{type:range,+field:${num_d},+start:-5,+end:10,+gap:5}}&wt=json&indent=true} hits=6 status=0 QTime=34
[junit4] 2> 894327 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f:{type:range,+field:${date},+start:'2001-01-01T00:00:00Z',+end:'2003-01-01T00:00:00Z',+gap:'%2B1YEAR'}}&wt=json&indent=true} hits=6 status=0 QTime=3
[junit4] 2> 894330 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f:{type:range,+field:${date},+start:'2002-01-01T00:00:00Z',+end:'2005-01-01T00:00:00Z',+gap:'%2B1YEAR',+++other:all,+facet:{+x:'avg(${num_d})'+}+}+}&wt=json&indent=true} hits=6 status=0 QTime=2
[junit4] 2> 894331 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f:{range:{field:${num_d},+start:-5,+end:10,+gap:5,+include:upper}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894332 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f:{range:{field:${num_d},+start:-5,+end:10,+gap:5,+++facet:{+x:'sum(${num_i})',+ny:{query:'${where_s}:NY'}}+++}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894333 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f:{range:{field:${num_d},+start:-5,+end:10,+gap:5,+other:all,+++facet:{+x:'sum(${num_i})',+ny:{query:'${where_s}:NY'}}+++}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894334 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={f:{type:range,+field:${num_d},+start:-5,+end:10,+gap:5,+other:all,+mincount:2,++++facet:{+x:'sum(${num_i})',+ny:{query:'${where_s}:NY'}}+++}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894336 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=id:(3+4+6)&json.facet={f:{range:{field:${num_d},+start:-5,+end:10,+gap:5,+other:all,+++facet:{+x:'sum(${num_i})',+ny:{query:'${where_s}:NY'}}+++}}}&wt=json&indent=true} hits=3 status=0 QTime=1
[junit4] 2> 894349 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={+sum1:'sum(${num_d})',+sumsq1:'sumsq(${num_d})',+avg1:'avg(${num_d})',+avg2:'avg(def(${num_d},0))',+min1:'min(${num_d})',+max1:'max(${num_d})',+numwhere:'unique(${where_s})',+unique_num_i:'unique(${num_i})',+unique_num_d:'unique(${num_d})',+unique_date:'unique(${date})',+where_hll:'hll(${where_s})',+hll_num_i:'hll(${num_i})',+hll_num_d:'hll(${num_d})',+hll_date:'hll(${date})',+med:'percentile(${num_d},50)',+perc:'percentile(${num_d},0,50.0,100)'+}&wt=json&indent=true} hits=6 status=0 QTime=13
[junit4] 2> 894350 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=id:DOESNOTEXIST&json.facet={+sum1:'sum(${num_d})',+sumsq1:'sumsq(${num_d})',+avg1:'avg(${num_d})',+min1:'min(${num_d})',+max1:'max(${num_d})',+numwhere:'unique(${where_s})',+unique_num_i:'unique(${num_i})',+unique_num_d:'unique(${num_d})',+unique_date:'unique(${date})',+where_hll:'hll(${where_s})',+hll_num_i:'hll(${num_i})',+hll_num_d:'hll(${num_d})',+hll_date:'hll(${date})',+med:'percentile(${num_d},50)',+perc:'percentile(${num_d},0,50.0,100)'+}&wt=json&indent=true} hits=0 status=0 QTime=0
[junit4] 2> 894351 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={cat:{terms:{${terms}+field:'${multi_ss}',+facet:{nj:{query:'${where_s}:NJ'}}++++}+++}}+}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894355 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={x:'unique(${multi_ss})',y:{query:{q:'id:2',+facet:{x:'unique(${multi_ss})'}+}}++,x2:'hll(${multi_ss})',y2:{query:{q:'id:2',+facet:{x:'hll(${multi_ss})'}+}}+++}&wt=json&indent=true} hits=6 status=0 QTime=3
[junit4] 2> 894356 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={x:{terms:{${terms}+field:'${multi_ss}',allBuckets:true}}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894357 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={+f0:{${terms}+type:terms,+field:${multi_ss},+allBuckets:true,+limit:0}+,f1:{${terms}+type:terms,+field:${multi_ss},+allBuckets:true,+limit:0,+offset:1}+,f2:{${terms}+type:terms,+field:${multi_ss},+allBuckets:true,+limit:0,+facet:{x:'sum(${num_d})'},+sort:'x+desc'+}+,f3:{${terms}+type:terms,+field:${multi_ss},+allBuckets:true,+limit:0,+missing:true,+facet:{x:'sum(${num_d})',+y:'avg(${num_d})'},+sort:'x+desc'+}+}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894365 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={+f0:{${terms}+type:terms,+field:${num_i},+allBuckets:true,+limit:0}+,f1:{${terms}+type:terms,+field:${num_i},+allBuckets:true,+limit:0,+offset:1}+,f2:{${terms}+type:terms,+field:${num_i},+allBuckets:true,+limit:0,+facet:{x:'sum(${num_d})'},+sort:'x+desc'+}+}&wt=json&indent=true} hits=6 status=0 QTime=8
[junit4] 2> 894385 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&facet=true&facet.version=2&facet.field={!key%3Df1}${cat_s}&facet.mincount=3&wt=json&indent=true} hits=6 status=0 QTime=19
[junit4] 2> 894386 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&facet=true&facet.version=2&facet.field={!key%3Df1}${super_s}&facet.prefix=s&facet.mincount=0&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894393 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&facet=true&facet.version=2&facet.range={!key%3Df}${num_d}&facet.range.start=-5&facet.range.end=10&facet.range.gap=5&f.f.facet.stat=x:sum(${num_i})&subfacet.f.query={!key%3Dny}${where_s}:NY&wt=json&indent=true} hits=6 status=0 QTime=6
[junit4] 2> 894394 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&facet=true&facet.version=2&facet.field={!key%3Df1}${cat_s}&facet.field={!key%3Df2}${cat_s}&f.f1.facet.sort=n1+desc&f.f1.facet.sort=n1+asc&facet.stat=n1:sum(${num_d})&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894396 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=id:(3+4+6)&facet=true&facet.version=2&facet.range={!key%3Df}${num_d}&facet.range.start=-5&facet.range.end=10&facet.range.gap=5&f.f.facet.stat=x:sum(${num_i})&subfacet.f.query={!key%3Dny}${where_s}:NY&facet.range.other=all&wt=json&indent=true} hits=3 status=0 QTime=0
[junit4] 2> 894404 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q={!cache%3Dfalse}*:*&fq={!tag%3Ddoc3,allfilt}-id:3&json.facet={f1:{${terms}+type:terms,+field:${cat_s},+domain:{excludeTags:doc3}+}++}&wt=json&indent=true} hits=5 status=0 QTime=8
[junit4] 2> 894408 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&fq={!tag%3Dabc}id:(2+3)&json.facet={+processEmpty:true,+f1:{query:{q:'${cat_s}:B',+facet:{nj:{query:'${where_s}:NJ'},+ny:{query:'${where_s}:NY'}}+,+excludeTags:[xyz,qaz]}},f2:{query:{q:'${cat_s}:B',+facet:{nj:{query:'${where_s}:NJ'},+ny:{query:'${where_s}:NY'}}+,+excludeTags:abc+}},f3:{query:{q:'${cat_s}:B',+facet:{nj:{query:'${where_s}:NJ'},+ny:{query:'${where_s}:NY'}}+,+excludeTags:'xyz,abc,qaz'+}},f4:{query:{q:'${cat_s}:B',+facet:{nj:{query:'${where_s}:NJ'},+ny:{query:'${where_s}:NY'}}+,+excludeTags:[xyz+,+abc+,+qaz]+}},f5:{query:{q:'${cat_s}:B',+facet:{nj:{query:'${where_s}:NJ'},+ny:{query:'${where_s}:NY'}}+,+excludeTags:[xyz,qaz]}},f6:{query:{q:'${cat_s}:B',+facet:{processEmpty:true,+nj:{query:'${where_s}:NJ'},+ny:{+type:query,+q:'${where_s}:NY',+excludeTags:abc}}++}},f7:{query:{q:'${cat_s}:B',+facet:{processEmpty:true,+nj:{query:'${where_s}:NJ'},+ny:{+type:query,+q:'${where_s}:NY',+excludeTags:xyz}}++}}}&wt=json&indent=true} hits=2 status=0 QTime=2
[junit4] 2> 894410 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q={!cache%3Dfalse}*:*&fq={!tag%3Ddoc6,allfilt}-id:6&fq={!tag%3Ddoc3,allfilt}-id:3&json.facet={processEmpty:true,++f0:{${terms}+type:terms,+field:${cat_s},++++++++++++++++++++++++++++++++++++facet:{nj:{query:'${where_s}:NJ'}}+}++,f1:{${terms}+type:terms,+field:${cat_s},+domain:{excludeTags:doc3},+++missing:true,++facet:{nj:{query:'${where_s}:NJ'}}+}++,f2:{${terms}+type:terms,+field:${cat_s},+domain:{excludeTags:allfilt},missing:true,++facet:{nj:{query:'${where_s}:NJ'}}+}++,f3:{${terms}+type:terms,+field:${cat_s},+domain:{excludeTags:doc6},+++missing:true,++facet:{nj:{query:'${where_s}:NJ'}}+}++}&wt=json&indent=true} hits=4 status=0 QTime=1
[junit4] 2> 894412 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&fq={!tag%3Ddoc6,allfilt}-id:6&fq={!tag%3Ddoc3,allfilt}-id:3&json.facet={processEmpty:true+,+f1:{type:range,+field:${num_d},+start:-5,+end:10,+gap:5,+other:all,+++facet:{+x:'sum(${num_i})',+ny:{query:'${where_s}:NY'}}+,+domain:{excludeTags:allfilt}+},+f2:{type:range,+field:${num_d},+start:-5,+end:10,+gap:5,+other:all,+++facet:{+x:'sum(${num_i})',+ny:{query:'${where_s}:NY'}}++}}&wt=json&indent=true} hits=4 status=0 QTime=1
[junit4] 2> 894415 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={+f1:{${terms}++type:field,+field:${num_i}+},f2:{${terms}++type:field,+field:${num_i},+sort:'count+asc'+},f3:{${terms}++type:field,+field:${num_i},+sort:'index+asc'+},f4:{${terms}++type:field,+field:${num_i},+sort:'index+desc'+},f5:{${terms}++type:field,+field:${num_i},+sort:'index+desc',+limit:1,+missing:true,+allBuckets:true,+numBuckets:true+},f6:{${terms}++type:field,+field:${num_i},+sort:'index+desc',+mincount:2,+numBuckets:true+},f7:{${terms}++type:field,+field:${num_i},+sort:'index+desc',+offset:2,+numBuckets:true+},f8:{${terms}++type:field,+field:${num_i},+sort:'index+desc',+offset:100,+numBuckets:true+},f9:{${terms}++type:field,+field:${num_i},+sort:'x+desc',+facet:{x:'avg(${num_d})'},+missing:true,+allBuckets:true,+numBuckets:true+},f10:{${terms}++type:field,+field:${num_i},+facet:{a:{query:'${cat_s}:A'}},+missing:true,+allBuckets:true,+numBuckets:true+},f11:{${terms}++type:field,+field:${num_i},+facet:{a:'unique(${num_d})'}+,missing:true,+allBuckets:true,+sort:'a+desc'+}}&wt=json&indent=true} hits=6 status=0 QTime=2
[junit4] 2> 894416 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={+f1:{${terms}++type:field,+field:${num_d}+},f2:{${terms}++type:field,+field:${num_d},+sort:'index+desc'+}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894417 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={+u+:+'unique(${Z_num_i})',+f1:{${terms}++type:field,+field:${Z_num_i}+}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894418 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=num_is,memSize=4562,tindexSize=46,time=0,phase1=0,nTerms=5,bigTerms=2,termInstances=6,uses=0}
[junit4] 2> 894418 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={++c1:'unique(${num_is})',+c2:'hll(${num_is})',f1:{${terms}+type:terms,+field:${num_is}+}++}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894419 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=num_fs,memSize=4562,tindexSize=46,time=0,phase1=0,nTerms=5,bigTerms=2,termInstances=6,uses=0}
[junit4] 2> 894420 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={++c1:'unique(${num_fs})',+c2:'hll(${num_fs})',f1:{${terms}+type:terms,+field:${num_fs}+}++}&wt=json&indent=true} hits=6 status=0 QTime=1
[junit4] 2> 894420 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={debugQuery=true&rows=0&noexist=noexist_s&cat_s=cat_s&where_s=where_s&num_d=num_d&num_i=num_i&super_s=super_s&val_b=val_b&date=date_dt&sparse_s=sparse_s&multi_ss=multi_ss&Z_num_i=Z_num_i&num_is=num_is&num_fs=num_fs&q=*:*&json.facet={cat0:{type:terms,+field:${cat_s},+sort:'count+desc',+limit:1,+overrequest:0},cat1:{type:terms,+field:${cat_s},+sort:'count+desc',+limit:1,+overrequest:1},catDef:{type:terms,+field:${cat_s},+sort:'count+desc',+limit:1,+overrequest:-1},catBig:{type:terms,+field:${cat_s},+sort:'count+desc',+offset:1,+limit:2147483647,+overrequest:2147483647}}&wt=json&indent=true} hits=6 status=0 QTime=0
[junit4] 2> 894422 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@40c79873[collection1] realtime]
[junit4] 2> 894423 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1549174133079670784)} 0 1
[junit4] 2> 894423 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1 (1549174133081767936)]} 0 0
[junit4] 2> 894424 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1 (1549174133082816512)]} 0 0
[junit4] 2> 894424 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[1 (1549174133082816513)]} 0 0
[junit4] 2> 894425 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[2 (1549174133082816514)]} 0 0
[junit4] 2> 894425 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[3 (1549174133083865088)]} 0 0
[junit4] 2> 894425 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 894425 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2c6b78d6
[junit4] 2> 894428 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@ac2edac[collection1] main]
[junit4] 2> 894428 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 894429 INFO (searcherExecutor-3499-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ac2edac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.0.0):C5/2:delGen=1)))}
[junit4] 2> 894429 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 894431 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[4 (1549174133089107968)]} 0 1
[junit4] 2> 894432 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{add=[5 (1549174133091205120)]} 0 0
[junit4] 2> 894432 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 894432 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2c6b78d6
[junit4] 2> 894435 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1fbfdf0e[collection1] main]
[junit4] 2> 894435 INFO (TEST-TestJsonFacets.testStats-seed#[6E3F85D9190C0879]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 894436 INFO (searcherExecutor-3499-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1fbfdf0e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.0.0):C5/2:delGen=1) Uninverting(_4(7.0.0):C2)))}
[junit4] 2> 894436 INFO (TEST-TestJsonFacet
[...truncated too long message...]
recovery for core=[collection1] coreNodeName=[core_node2]
[junit4] 2> 1236140 WARN (updateExecutor-1553-thread-2-processing-n:127.0.0.1:65497_naq%2Fh) [n:127.0.0.1:65497_naq%2Fh c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.DefaultSolrCoreState Skipping recovery because Solr is shutdown
[junit4] 2> 1236259 INFO (zkCallback-1556-thread-5-processing-n:127.0.0.1:65497_naq%2Fh) [n:127.0.0.1:65497_naq%2Fh ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1240898 INFO (recoveryExecutor-1554-thread-1-processing-n:127.0.0.1:65497_naq%2Fh x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:65497_naq%2Fh c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy RecoveryStrategy has been closed
[junit4] 2> 1240898 INFO (recoveryExecutor-1554-thread-1-processing-n:127.0.0.1:65497_naq%2Fh x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:65497_naq%2Fh c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Finished recovery process, successful=[false]
[junit4] 2> 1240898 INFO (TEST-RecoveryZkTest.test-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.Overseer Overseer (id=96823397011554320-127.0.0.1:65497_naq%2Fh-n_0000000003) closing
[junit4] 2> 1240899 INFO (OverseerStateUpdate-96823397011554320-127.0.0.1:65497_naq%2Fh-n_0000000003) [n:127.0.0.1:65497_naq%2Fh ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:65497_naq%2Fh
[junit4] 2> 1242400 INFO (zkCallback-1556-thread-4-processing-n:127.0.0.1:65497_naq%2Fh) [n:127.0.0.1:65497_naq%2Fh c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@331fc988
[junit4] 2> 1242401 WARN (zkCallback-1556-thread-4-processing-n:127.0.0.1:65497_naq%2Fh) [n:127.0.0.1:65497_naq%2Fh c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Stopping recovery for core=[collection1] coreNodeName=[core_node2]
[junit4] 2> 1242402 WARN (zkCallback-1556-thread-5-processing-n:127.0.0.1:65497_naq%2Fh) [n:127.0.0.1:65497_naq%2Fh ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1242421 INFO (TEST-RecoveryZkTest.test-seed#[6E3F85D9190C0879]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1c2c07e{SSL,[ssl, http/1.1]}{127.0.0.1:65497}
[junit4] 2> 1242421 INFO (TEST-RecoveryZkTest.test-seed#[6E3F85D9190C0879]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@60195801{/naq/h,null,UNAVAILABLE}
[junit4] 2> 1242423 INFO (TEST-RecoveryZkTest.test-seed#[6E3F85D9190C0879]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65477 65477
[junit4] 2> 1242446 INFO (Thread-1337) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:65477 65477
[junit4] 2> 1244717 WARN (Thread-1337) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 3 /solr/collections/collection1/state.json
[junit4] 2> 2 /solr/overseer_elect/election/96823397011554314-127.0.0.1:65491_naq%2Fh-n_0000000001
[junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/96823397011554314-core_node1-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 136 /solr/overseer/collection-queue-work
[junit4] 2> 29 /solr/overseer/queue
[junit4] 2> 11 /solr/overseer/queue-work
[junit4] 2> 5 /solr/collections
[junit4] 2> 4 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RecoveryZkTest -Dtests.method=test -Dtests.seed=6E3F85D9190C0879 -Dtests.slow=true -Dtests.locale=es-CO -Dtests.timezone=Europe/Berlin -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 142s J0 | RecoveryZkTest.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: There are still nodes recoverying - waited for 120 seconds
[junit4] > at __randomizedtesting.SeedInfo.seed([6E3F85D9190C0879:E66BBA03B7F06581]:0)
[junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:184)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:862)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1418)
[junit4] > at org.apache.solr.cloud.RecoveryZkTest.test(RecoveryZkTest.java:105)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1244877 INFO (SUITE-RecoveryZkTest-seed#[6E3F85D9190C0879]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.RecoveryZkTest_6E3F85D9190C0879-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {rnd_b=PostingsFormat(name=Direct), _version_=PostingsFormat(name=LuceneFixedGap), a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_i=PostingsFormat(name=Direct), id=PostingsFormat(name=Direct)}, docValues:{}, maxPointsInLeafNode=1086, maxMBSortInHeap=6.230687430271817, sim=ClassicSimilarity, locale=es-CO, timezone=Europe/Berlin
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=263908056,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestReqParamsAPI, ChaosMonkeyNothingIsSafeTest, TestNoOpRegenerator, TestMinMaxOnMultiValuedField, TestXmlQParser, CollectionStateFormat2Test, TestUseDocValuesAsStored2, CustomCollectionTest, TestPivotHelperCode, ZkStateReaderTest, TestDocBasedVersionConstraints, TestLockTree, AddSchemaFieldsUpdateProcessorFactoryTest, TestMaxScoreQueryParser, TestGroupingSearch, TestSolrDeletionPolicy1, SynonymTokenizerTest, SharedFSAutoReplicaFailoverUtilsTest, MoreLikeThisHandlerTest, ParsingFieldUpdateProcessorsTest, TestMacroExpander, TestConfigOverlay, TestReplicaProperties, TestFieldCacheWithThreads, EnumFieldTest, RestartWhileUpdatingTest, TestLeaderInitiatedRecoveryThread, QueryElevationComponentTest, RulesTest, TestFieldTypeResource, PluginInfoTest, AssignTest, TestLFUCache, TestSurroundQueryParser, StatelessScriptUpdateProcessorFactoryTest, ZkControllerTest, TestCustomStream, OverseerTest, TestRTGBase, TestLRUCache, MBeansHandlerTest, CdcrReplicationDistributedZkTest, TestManagedSchemaAPI, TestXIncludeConfig, TriLevelCompositeIdRoutingTest, DeleteStatusTest, TestElisionMultitermQuery, TestFieldCacheSanityChecker, TestCSVResponseWriter, FullHLLTest, HdfsCollectionsAPIDistributedZkTest, ZkCLITest, TestPartialUpdateDeduplication, TestFastOutputStream, TestDocumentBuilder, BooleanFieldTest, DocValuesTest, UnloadDistributedZkTest, TestRawResponseWriter, NumericFieldsTest, CloudExitableDirectoryReaderTest, TestBlendedInfixSuggestions, OutOfBoxZkACLAndCredentialsProvidersTest, CoreAdminCreateDiscoverTest, TestStressLucene, TestDynamicFieldResource, TestIBSimilarityFactory, TestCollationFieldDocValues, IndexBasedSpellCheckerTest, FastVectorHighlighterTest, DocValuesMultiTest, TestDefaultStatsCache, TestCloudDeleteByQuery, PolyFieldTest, HighlighterTest, TestHighlightDedupGrouping, TestSmileRequest, TestExclusionRuleCollectionAccess, CoreSorterTest, MergeStrategyTest, HdfsThreadLeakTest, SortSpecParsingTest, DistributedQueueTest, TestManagedSynonymFilterFactory, TestHdfsBackupRestoreCore, SimpleFacetsTest, PrimitiveFieldTypeTest, TestSQLHandler, DocumentAnalysisRequestHandlerTest, TestStressReorder, TestGraphTermsQParserPlugin, DistributedMLTComponentTest, TestShortCircuitedRequests, CoreMergeIndexesAdminHandlerTest, TolerantUpdateProcessorTest, OpenExchangeRatesOrgProviderTest, HdfsUnloadDistributedZkTest, RequiredFieldsTest, BufferStoreTest, TestFieldSortValues, TestScoreJoinQPNoScore, DirectSolrConnectionTest, DistanceUnitsTest, LeaderElectionTest, DistribDocExpirationUpdateProcessorTest, TestNumericTerms32, OverseerCollectionConfigSetProcessorTest, TestRestManager, SolrTestCaseJ4Test, TestStressVersions, SuggestComponentContextFilterQueryTest, TestCloudRecovery, TestImplicitCoreProperties, TestConfigSetsAPIZkFailure, TestCoreContainer, DocumentBuilderTest, TestHashPartitioner, TestFaceting, TestBM25SimilarityFactory, TestExtendedDismaxParser, ClusterStateUpdateTest, JSONWriterTest, DataDrivenBlockJoinTest, TestCharFilters, ZkNodePropsTest, SecurityConfHandlerTest, TestJmxIntegration, JavabinLoaderTest, HdfsBasicDistributedZk2Test, StatsReloadRaceTest, FacetPivotSmallTest, TestSweetSpotSimilarityFactory, TestSchemaManager, DirectSolrSpellCheckerTest, HdfsChaosMonkeyNothingIsSafeTest, CopyFieldTest, SuggesterFSTTest, CollectionsAPIAsyncDistributedZkTest, TestJsonFacetRefinement, UniqFieldsUpdateProcessorFactoryTest, TestPostingsSolrHighlighter, TestPerFieldSimilarity, RecoveryZkTest]
[junit4] Completed [255/642 (2!)] on J0 in 141.72s, 1 test, 1 failure <<< FAILURES!
[...truncated 61619 lines...]