Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22511/ Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseSerialGC
4 tests failed. FAILED: org.apache.solr.TestDistributedGrouping.test Error Message: .grouped[a_i1].doclist.maxScore:1.0!=null Stack Trace: junit.framework.AssertionFailedError: .grouped[a_i1].doclist.maxScore:1.0!=null at __randomizedtesting.SeedInfo.seed([76A317833A82CA4B:FEF72859947EA7B3]:0) at junit.framework.Assert.fail(Assert.java:50) at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:928) at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:955) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:613) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:589) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:568) at org.apache.solr.TestDistributedGrouping.test(TestDistributedGrouping.java:176) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1034) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.TestDistributedGrouping.test Error Message: .grouped[a_i1].doclist.maxScore:1.0!=null Stack Trace: junit.framework.AssertionFailedError: .grouped[a_i1].doclist.maxScore:1.0!=null at __randomizedtesting.SeedInfo.seed([76A317833A82CA4B:FEF72859947EA7B3]:0) at junit.framework.Assert.fail(Assert.java:50) at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:928) at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:955) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:613) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:589) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:568) at org.apache.solr.TestDistributedGrouping.test(TestDistributedGrouping.java:176) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1034) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.TestDistributedGrouping.test Error Message: .grouped[a_i1].doclist.maxScore:1.0!=null Stack Trace: junit.framework.AssertionFailedError: .grouped[a_i1].doclist.maxScore:1.0!=null at __randomizedtesting.SeedInfo.seed([76A317833A82CA4B:FEF72859947EA7B3]:0) at junit.framework.Assert.fail(Assert.java:50) at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:928) at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:955) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:613) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:589) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:568) at org.apache.solr.TestDistributedGrouping.test(TestDistributedGrouping.java:176) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1034) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.TestDistributedGrouping.test Error Message: .grouped[a_i1].doclist.maxScore:1.0!=null Stack Trace: junit.framework.AssertionFailedError: .grouped[a_i1].doclist.maxScore:1.0!=null at __randomizedtesting.SeedInfo.seed([76A317833A82CA4B:FEF72859947EA7B3]:0) at junit.framework.Assert.fail(Assert.java:50) at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:928) at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:955) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:613) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:589) at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:568) at org.apache.solr.TestDistributedGrouping.test(TestDistributedGrouping.java:176) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1034) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 13586 lines...] [junit4] Suite: org.apache.solr.TestDistributedGrouping [junit4] 2> 882691 INFO (SUITE-TestDistributedGrouping-seed#[76A317833A82CA4B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-001/init-core-data-001 [junit4] 2> 882691 WARN (SUITE-TestDistributedGrouping-seed#[76A317833A82CA4B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2 [junit4] 2> 882691 INFO (SUITE-TestDistributedGrouping-seed#[76A317833A82CA4B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 882692 INFO (SUITE-TestDistributedGrouping-seed#[76A317833A82CA4B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 882692 INFO (SUITE-TestDistributedGrouping-seed#[76A317833A82CA4B]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 882765 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-001/tempDir-001/control/cores/collection1 [junit4] 2> 882766 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 9.0.4+11 [junit4] 2> 882767 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 882767 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 882767 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 882767 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2bdd05ad{/,null,AVAILABLE} [junit4] 2> 882768 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@8b56e6b{SSL,[ssl, http/1.1]}{127.0.0.1:٣٨٢٢٩} [junit4] 2> 882768 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.Server Started @٨٨٢٨١٠ms [junit4] 2> 882768 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=38229, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-001/tempDir-001/control/cores} [junit4] 2> 882768 ERROR (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 882768 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 882768 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 882768 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 882768 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 882768 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-23T18:11:40.077394Z [junit4] 2> 882768 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-001/tempDir-001/control/solr.xml [junit4] 2> 882770 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 882770 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 882770 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6525f8, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 882863 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 882863 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 882872 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6525f8 [junit4] 2> 882878 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6525f8 [junit4] 2> 882878 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6525f8 [junit4] 2> 882879 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-001/tempDir-001/control/cores [junit4] 2> 882879 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 882880 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 882895 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 882918 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 882974 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 882980 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 882980 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6525f8 [junit4] 2> 882980 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 882980 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 882981 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=18.9365234375, floorSegmentMB=1.4609375, forceMergeDeletesPctAllowed=12.990017795036973, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=42.18275639237045 [junit4] 2> 883010 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 883010 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 883011 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 883011 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 883011 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6568905244558689] [junit4] 2> 883011 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f5b79a[collection1] main] [junit4] 2> 883012 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 883056 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 883131 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 883131 INFO (searcherExecutor-4686-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 883131 INFO (coreLoadExecutor-4685-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1606805881293373440 [junit4] 2> 883131 INFO (searcherExecutor-4686-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 883131 INFO (searcherExecutor-4686-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 883131 INFO (searcherExecutor-4686-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 883131 INFO (searcherExecutor-4686-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 883131 INFO (searcherExecutor-4686-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 883132 INFO (searcherExecutor-4686-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 883132 INFO (searcherExecutor-4686-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 883132 INFO (searcherExecutor-4686-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 883132 INFO (searcherExecutor-4686-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2f5b79a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 883194 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 883195 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 9.0.4+11 [junit4] 2> 883196 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 883196 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 883196 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 883197 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5e28a518{/,null,AVAILABLE} [junit4] 2> 883197 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@f91ed37{SSL,[ssl, http/1.1]}{127.0.0.1:٤٠٦١٥} [junit4] 2> 883197 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.Server Started @٨٨٣٢٤٠ms [junit4] 2> 883197 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=40615, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-001/tempDir-001/shard0/cores} [junit4] 2> 883197 ERROR (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 883197 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 883197 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 883197 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 883197 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 883198 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-23T18:11:40.507032Z [junit4] 2> 883198 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-001/tempDir-001/shard0/solr.xml [junit4] 2> 883199 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 883199 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 883200 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6525f8, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 883299 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 883299 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 883311 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6525f8 [junit4] 2> 883318 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6525f8 [junit4] 2> 883319 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6525f8 [junit4] 2> 883320 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-001/tempDir-001/shard0/cores [junit4] 2> 883320 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 883320 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 883335 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 883347 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 883403 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 883408 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 883408 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3c6525f8 [junit4] 2> 883408 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 883408 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 883409 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=18.9365234375, floorSegmentMB=1.4609375, forceMergeDeletesPctAllowed=12.990017795036973, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=42.18275639237045 [junit4] 2> 883437 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 883437 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 883438 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 883438 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 883439 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6568905244558689] [junit4] 2> 883439 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@61df2146[collection1] main] [junit4] 2> 883439 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 883455 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 883548 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 883548 INFO (searcherExecutor-4700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 883548 INFO (coreLoadExecutor-4699-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1606805881730629632 [junit4] 2> 883548 INFO (searcherExecutor-4700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 883548 INFO (searcherExecutor-4700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 883548 INFO (searcherExecutor-4700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 883548 INFO (searcherExecutor-4700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 883549 INFO (searcherExecutor-4700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 883549 INFO (searcherExecutor-4700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 883549 INFO (searcherExecutor-4700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 883549 INFO (searcherExecutor-4700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 883549 INFO (searcherExecutor-4700-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@61df2146[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 883550 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 883567 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1606805881749504000)} 0 0 [junit4] 2> 883580 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1606805881763135488)} 0 0 [junit4] 2> 883581 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1606805881765232640,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 883581 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 883581 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 883581 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 0 [junit4] 2> 883582 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1606805881766281216,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 883582 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 883582 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 883582 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 0 [junit4] 2> 883583 INFO (qtp659721530-13183) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} hits=0 status=0 QTime=0 [junit4] 2> 883591 INFO (qtp925166878-13221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=https://127.0.0.1:40615//collection1|[ff01::083]:33332/|[ff01::114]:33332/&rows=100&version=2&q=*:*&NOW=1532369500893&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=4 [junit4] 2> 883595 INFO (qtp925166878-13203) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=https://127.0.0.1:40615//collection1|[ff01::083]:33332/|[ff01::114]:33332/&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} status=0 QTime=11 [junit4] 2> 883596 INFO (qtp659721530-13184) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} hits=0 status=0 QTime=0 [junit4] 2> 883598 INFO (qtp925166878-13205) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&group.distributed.first=true&distrib=false&hl=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::213]:33332/|https://127.0.0.1:40615//collection1&rows=100&version=2&q=*:*&NOW=1532369500905&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 883599 INFO (qtp925166878-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|https://127.0.0.1:40615//collection1&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true} status=0 QTime=2 [junit4] 2> 883599 INFO (qtp659721530-13183) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&facet.field=a_t&distrib=false&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true} hits=0 status=0 QTime=0 [junit4] 2> 883602 INFO (qtp925166878-13221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:40615//collection1&rows=100&version=2&q=*:*&NOW=1532369500909&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 883606 INFO (qtp925166878-13205) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:40615//collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1532369500909&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=3 [junit4] 2> 883608 INFO (qtp925166878-13203) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:40615//collection1&facet.field=a_t&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true} status=0 QTime=8 [junit4] 2> 883609 INFO (qtp659721530-13184) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1} hits=0 status=0 QTime=0 [junit4] 2> 883612 INFO (qtp925166878-13221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=https://127.0.0.1:40615//collection1&rows=100&version=2&q=*:*&stats=false&NOW=1532369500920&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0 [junit4] 2> 883613 INFO (qtp925166878-13205) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=516&start=0&sort=a_i1+asc,+id+asc&shard.url=https://127.0.0.1:40615//collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1532369500920&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0 [junit4] 2> 883613 INFO (qtp925166878-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=https://127.0.0.1:40615//collection1&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=2 [junit4] 2> 883614 INFO (qtp659721530-13183) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=subject&distrib=false&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&wt=javabin&group.field=a_i1&group=true} hits=0 status=0 QTime=0 [junit4] 2> 883616 INFO (qtp925166878-13221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=subject&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:40615//collection1&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&NOW=1532369500923&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 883617 INFO (qtp925166878-13203) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=subject&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&shards=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:40615//collection1&spellcheck=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=2 [junit4] 2> 883620 INFO (qtp659721530-13184) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={facet.field=a_t&distrib=false&fl=id,a_i1&group.limit=-1&fq=a_s:a&sort=a_i1+asc,+id+asc&rows=100&version=2&q=*:*&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true} hits=0 status=0 QTime=2 [junit4] 2> 883622 INFO (qtp925166878-13205) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=https://127.0.0.1:40615//collection1&rows=100&version=2&q=*:*&group.truncate=true&NOW=1532369500930&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 883623 INFO (qtp925166878-13221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=https://127.0.0.1:40615//collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&group.truncate=true&NOW=1532369500930&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 883623 INFO (qtp925166878-13204) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={facet.field=a_t&fl=id,a_i1&group.limit=-1&fq=a_s:a&sort=a_i1+asc,+id+asc&rows=100&version=2&q=*:*&shards=https://127.0.0.1:40615//collection1&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=2 [junit4] 2> 883625 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1606805881810321408)]} 0 1 [junit4] 2> 883627 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1606805881812418560)]} 0 0 [junit4] 2> 883627 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1606805881813467136)]} 0 0 [junit4] 2> 883628 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1606805881814515712)]} 0 0 [junit4] 2> 883629 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1606805881815564288)]} 0 0 [junit4] 2> 883630 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1606805881816612864)]} 0 0 [junit4] 2> 883631 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1606805881817661440)]} 0 0 [junit4] 2> 883632 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1606805881818710016)]} 0 0 [junit4] 2> 883633 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1606805881818710016)]} 0 0 [junit4] 2> 883633 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1606805881819758592)]} 0 0 [junit4] 2> 883634 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1606805881820807168)]} 0 0 [junit4] 2> 883635 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1606805881821855744)]} 0 0 [junit4] 2> 883636 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1606805881821855744)]} 0 0 [junit4] 2> 883636 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1606805881822904320)]} 0 0 [junit4] 2> 883637 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1606805881823952896)]} 0 0 [junit4] 2> 883638 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1606805881823952896)]} 0 0 [junit4] 2> 883638 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1606805881825001472)]} 0 0 [junit4] 2> 883639 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1606805881826050048)]} 0 0 [junit4] 2> 883640 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1606805881826050048)]} 0 0 [junit4] 2> 883640 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1606805881827098624)]} 0 0 [junit4] 2> 883641 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1606805881828147200)]} 0 0 [junit4] 2> 883642 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1606805881829195776)]} 0 0 [junit4] 2> 883643 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1606805881829195776)]} 0 0 [junit4] 2> 883643 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1606805881830244352)]} 0 0 [junit4] 2> 883645 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1606805881831292928)]} 0 0 [junit4] 2> 883645 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1606805881832341504)]} 0 0 [junit4] 2> 883646 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1606805881833390080)]} 0 0 [junit4] 2> 883647 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1606805881834438656)]} 0 0 [junit4] 2> 883647 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1606805881834438656)]} 0 0 [junit4] 2> 883648 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1606805881835487232)]} 0 0 [junit4] 2> 883649 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1606805881836535808)]} 0 0 [junit4] 2> 883650 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1606805881836535808)]} 0 0 [junit4] 2> 883653 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[18 (1606805881840730112)]} 0 0 [junit4] 2> 883654 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[18 (1606805881841778688)]} 0 0 [junit4] 2> 883655 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[19 (1606805881842827264)]} 0 0 [junit4] 2> 883656 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[19 (1606805881843875840)]} 0 0 [junit4] 2> 883657 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[20 (1606805881843875840)]} 0 0 [junit4] 2> 883657 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[20 (1606805881844924416)]} 0 0 [junit4] 2> 883658 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[21 (1606805881845972992)]} 0 0 [junit4] 2> 883659 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[21 (1606805881847021568)]} 0 0 [junit4] 2> 883660 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[22 (1606805881847021568)]} 0 0 [junit4] 2> 883660 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[22 (1606805881848070144)]} 0 0 [junit4] 2> 883661 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[23 (1606805881849118720)]} 0 0 [junit4] 2> 883662 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[23 (1606805881850167296)]} 0 0 [junit4] 2> 883663 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1606805881850167296)]} 0 0 [junit4] 2> 883663 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1606805881851215872)]} 0 0 [junit4] 2> 883664 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1606805881852264448)]} 0 0 [junit4] 2> 883665 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1606805881852264448)]} 0 0 [junit4] 2> 883665 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1606805881853313024)]} 0 0 [junit4] 2> 883666 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1606805881854361600)]} 0 0 [junit4] 2> 883666 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1606805881854361600)]} 0 0 [junit4] 2> 883666 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1606805881854361601)]} 0 0 [junit4] 2> 883667 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1606805881855410176)]} 0 0 [junit4] 2> 883667 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1606805881855410176)]} 0 0 [junit4] 2> 883668 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1606805881856458752)]} 0 0 [junit4] 2> 883669 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1606805881856458752)]} 0 0 [junit4] 2> 883669 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1606805881857507328)]} 0 0 [junit4] 2> 883670 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1606805881857507328)]} 0 0 [junit4] 2> 883670 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1606805881858555904)]} 0 0 [junit4] 2> 883671 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1606805881859604480)]} 0 0 [junit4] 2> 883671 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1606805881859604480)]} 0 0 [junit4] 2> 883672 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1606805881859604481)]} 0 0 [junit4] 2> 883672 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1606805881860653056)]} 0 0 [junit4] 2> 883673 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1606805881860653056)]} 0 0 [junit4] 2> 883673 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1606805881861701632)]} 0 0 [junit4] 2> 883673 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1606805881861701632)]} 0 0 [junit4] 2> 883674 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1606805881862750208)]} 0 0 [junit4] 2> 883674 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1606805881862750208)]} 0 0 [junit4] 2> 883675 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1606805881863798784)]} 0 0 [junit4] 2> 883675 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1606805881863798784)]} 0 0 [junit4] 2> 883676 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1606805881863798785)]} 0 0 [junit4] 2> 883676 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1606805881864847360)]} 0 0 [junit4] 2> 883677 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1606805881864847360)]} 0 0 [junit4] 2> 883677 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1606805881865895936)]} 0 0 [junit4] 2> 883677 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1606805881865895936)]} 0 0 [junit4] 2> 883678 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1606805881866944512)]} 0 0 [junit4] 2> 883679 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1606805881866944512)]} 0 0 [junit4] 2> 883679 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1606805881867993088)]} 0 0 [junit4] 2> 883679 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1606805881867993088)]} 0 0 [junit4] 2> 883680 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1606805881869041664)]} 0 0 [junit4] 2> 883680 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1606805881869041664)]} 0 0 [junit4] 2> 883681 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1606805881869041665)]} 0 0 [junit4] 2> 883681 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1606805881870090240)]} 0 0 [junit4] 2> 883681 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1606805881870090240)]} 0 0 [junit4] 2> 883682 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1606805881871138816)]} 0 0 [junit4] 2> 883682 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1606805881871138816)]} 0 0 [junit4] 2> 883683 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1606805881872187392)]} 0 0 [junit4] 2> 883683 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1606805881872187392)]} 0 0 [junit4] 2> 883684 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1606805881872187393)]} 0 0 [junit4] 2> 883684 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1606805881873235968)]} 0 0 [junit4] 2> 883685 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1606805881873235968)]} 0 0 [junit4] 2> 883685 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1606805881874284544)]} 0 0 [junit4] 2> 883686 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1606805881874284544)]} 0 0 [junit4] 2> 883686 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1606805881875333120)]} 0 0 [junit4] 2> 883687 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1606805881875333120)]} 0 0 [junit4] 2> 883687 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1606805881876381696)]} 0 0 [junit4] 2> 883688 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1606805881877430272)]} 0 0 [junit4] 2> 883688 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1606805881877430272)]} 0 0 [junit4] 2> 883689 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1606805881878478848)]} 0 0 [junit4] 2> 883689 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1606805881878478848)]} 0 0 [junit4] 2> 883690 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1606805881879527424)]} 0 0 [junit4] 2> 883690 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1606805881879527424)]} 0 0 [junit4] 2> 883691 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1606805881880576000)]} 0 0 [junit4] 2> 883692 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1606805881880576000)]} 0 0 [junit4] 2> 883692 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1606805881881624576)]} 0 0 [junit4] 2> 883693 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1606805881881624576)]} 0 0 [junit4] 2> 883693 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1606805881882673152)]} 0 0 [junit4] 2> 883694 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1606805881882673152)]} 0 0 [junit4] 2> 883694 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1606805881883721728)]} 0 0 [junit4] 2> 883695 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1606805881884770304)]} 0 0 [junit4] 2> 883695 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1606805881884770304)]} 0 0 [junit4] 2> 883696 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1606805881885818880)]} 0 0 [junit4] 2> 883697 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1606805881885818880)]} 0 0 [junit4] 2> 883697 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1606805881886867456)]} 0 0 [junit4] 2> 883698 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1606805881886867456)]} 0 0 [junit4] 2> 883698 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1606805881887916032)]} 0 0 [junit4] 2> 883699 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1606805881888964608)]} 0 0 [junit4] 2> 883699 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1606805881888964608)]} 0 0 [junit4] 2> 883700 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1606805881890013184)]} 0 0 [junit4] 2> 883701 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1606805881890013184)]} 0 0 [junit4] 2> 883701 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1606805881891061760)]} 0 0 [junit4] 2> 883702 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1606805881892110336)]} 0 0 [junit4] 2> 883703 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1606805881892110336)]} 0 0 [junit4] 2> 883703 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1606805881893158912)]} 0 0 [junit4] 2> 883704 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1606805881894207488)]} 0 0 [junit4] 2> 883704 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1606805881894207488)]} 0 0 [junit4] 2> 883706 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1606805881895256064)]} 0 1 [junit4] 2> 883706 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1606805881896304640)]} 0 0 [junit4] 2> 883707 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1606805881897353216)]} 0 0 [junit4] 2> 883708 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1606805881897353216)]} 0 0 [junit4] 2> 883708 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1606805881898401792)]} 0 0 [junit4] 2> 883709 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1606805881899450368)]} 0 0 [junit4] 2> 883709 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1606805881899450368)]} 0 0 [junit4] 2> 883710 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1606805881900498944)]} 0 0 [junit4] 2> 883711 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1606805881900498944)]} 0 0 [junit4] 2> 883711 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1606805881901547520)]} 0 0 [junit4] 2> 883711 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1606805881901547520)]} 0 0 [junit4] 2> 883712 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1606805881902596096)]} 0 0 [junit4] 2> 883712 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1606805881902596096)]} 0 0 [junit4] 2> 883713 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1606805881902596097)]} 0 0 [junit4] 2> 883713 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1606805881903644672)]} 0 0 [junit4] 2> 883713 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1606805881903644672)]} 0 0 [junit4] 2> 883714 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1606805881904693248)]} 0 0 [junit4] 2> 883714 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1606805881904693248)]} 0 0 [junit4] 2> 883715 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[500 (1606805881905741824)]} 0 0 [junit4] 2> 883715 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[500 (1606805881905741824)]} 0 0 [junit4] 2> 883716 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[501 (1606805881906790400)]} 0 0 [junit4] 2> 883716 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[501 (1606805881906790400)]} 0 0 [junit4] 2> 883716 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[502 (1606805881906790401)]} 0 0 [junit4] 2> 883717 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[502 (1606805881907838976)]} 0 0 [junit4] 2> 883717 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[503 (1606805881907838976)]} 0 0 [junit4] 2> 883718 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[503 (1606805881907838977)]} 0 0 [junit4] 2> 883718 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[504 (1606805881908887552)]} 0 0 [junit4] 2> 883719 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[504 (1606805881909936128)]} 0 0 [junit4] 2> 883720 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[505 (1606805881910984704)]} 0 0 [junit4] 2> 883720 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[505 (1606805881910984704)]} 0 0 [junit4] 2> 883721 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[506 (1606805881910984705)]} 0 0 [junit4] 2> 883721 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[506 (1606805881912033280)]} 0 0 [junit4] 2> 883721 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[507 (1606805881912033280)]} 0 0 [junit4] 2> 883722 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[507 (1606805881913081856)]} 0 0 [junit4] 2> 883722 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[508 (1606805881913081856)]} 0 0 [junit4] 2> 883722 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[508 (1606805881913081857)]} 0 0 [junit4] 2> 883723 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[509 (1606805881914130432)]} 0 0 [junit4] 2> 883723 INFO (qtp925166878-13204) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[509 (1606805881914130432)]} 0 0 [junit4] 2> 883723 INFO (qtp659721530-13184) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[510 (1606805881914130433)]} 0 0 [junit4] 2> 883724 INFO (qtp925166878-13203) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[510 (1606805881915179008)]} 0 0 [junit4] 2> 883724 INFO (qtp659721530-13183) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [...truncated too long message...] ter [org.apache.solr.metrics.reporters.SolrJmxReporter@6fc51c2d: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@315b7319 [junit4] 2> 20125 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 20125 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3b4fa15: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@e4771d4 [junit4] 2> 20129 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 20129 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2c58fba0: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@789dc91e [junit4] 2> 20130 INFO (coreCloseExecutor-62-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7a11289d [junit4] 2> 20130 INFO (coreCloseExecutor-62-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=7a11289d [junit4] 2> 20130 INFO (coreCloseExecutor-62-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@9329e39: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6317cd6f [junit4] 2> 20143 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7b23ad42{/,null,UNAVAILABLE} [junit4] 2> 20143 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 20152 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@355f9fb9{SSL,[ssl, http/1.1]}{127.0.0.1:٠} [junit4] 2> 20153 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1616294981 [junit4] 2> 20153 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 20153 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@50a6ebfb: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@274be7ab [junit4] 2> 20162 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 20162 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55ed8b16: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@e4771d4 [junit4] 2> 20167 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 20167 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@258032a9: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@789dc91e [junit4] 2> 20176 INFO (coreCloseExecutor-64-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@590162a0 [junit4] 2> 20176 INFO (coreCloseExecutor-64-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=590162a0 [junit4] 2> 20176 INFO (coreCloseExecutor-64-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a45d03d: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1f867d83 [junit4] 2> 20199 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5f3b721{/,null,UNAVAILABLE} [junit4] 2> 20199 INFO (TEST-TestDistributedGrouping.test-seed#[76A317833A82CA4B]) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedGrouping -Dtests.method=test -Dtests.seed=76A317833A82CA4B -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=ar-TD -Dtests.timezone=Europe/Malta -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 4.40s J2 | TestDistributedGrouping.test <<< [junit4] > Throwable #1: junit.framework.AssertionFailedError: .grouped[a_i1].doclist.maxScore:1.0!=null [junit4] > at __randomizedtesting.SeedInfo.seed([76A317833A82CA4B:FEF72859947EA7B3]:0) [junit4] > at junit.framework.Assert.fail(Assert.java:50) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:928) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:955) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:613) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:589) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:568) [junit4] > at org.apache.solr.TestDistributedGrouping.test(TestDistributedGrouping.java:176) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1034) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983) [junit4] > at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_76A317833A82CA4B-002 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4722fe31), locale=ar-TD, timezone=Europe/Malta [junit4] 2> NOTE: Linux 4.15.0-24-generic amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=8,threads=1,free=416642000,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [TestDistributedGrouping, TestDistributedGrouping] [junit4] Completed [5/5 (5!)] on J2 in 4.49s, 1 test, 1 failure <<< FAILURES! [...truncated 15 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1568: The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1092: There were test failures: 5 suites, 5 tests, 5 failures [seed: 76A317833A82CA4B] Total time: 23 seconds [repro] Setting last failure code to 256 [repro] Failures: [repro] 5/5 failed: org.apache.solr.TestDistributedGrouping [repro] Exiting with code 256 + mv lucene/build lucene/build.repro + mv solr/build solr/build.repro + mv lucene/build.orig lucene/build + mv solr/build.orig solr/build Archiving artifacts Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac) Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 <Git Blamer> Using GitBlamer to create author and commit information for all warnings. <Git Blamer> GIT_COMMIT=028c86b1fa70faeec4f7705698a59557f58bcbb0, workspace=/var/lib/jenkins/workspace/Lucene-Solr-master-Linux [WARNINGS] Computing warning deltas based on reference build #22510 Recording test results Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures) Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2 Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org