You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/07/23 22:36:56 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 4752
- Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4752/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
Error Message:
Error from server at http://127.0.0.1:50220/solr: Error in command payload
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteExecutionException: Error from server at http://127.0.0.1:50220/solr: Error in command payload
at __randomizedtesting.SeedInfo.seed([34B4578291158E4C]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteExecutionException.create(HttpSolrClient.java:829)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:620)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.setupCluster(TriggerIntegrationTest.java:96)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:874)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: 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([34B4578291158E4C:BCE068583FE9E3B4]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12148 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34B4578291158E4C-001/init-core-data-001
[junit4] 2> 81228 WARN (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=17 numCloses=17
[junit4] 2> 81228 INFO (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 81233 INFO (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 81233 INFO (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 81238 INFO (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34B4578291158E4C-001/tempDir-001
[junit4] 2> 81273 INFO (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 81279 INFO (Thread-52) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 81279 INFO (Thread-52) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 81726 INFO (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:50211
[junit4] 2> 81799 ERROR (Thread-52) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 82066 INFO (zkConnectionManagerCallback-18-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 82188 INFO (jetty-launcher-15-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 82188 INFO (jetty-launcher-15-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 82188 INFO (jetty-launcher-15-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 82188 INFO (jetty-launcher-15-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 82193 INFO (jetty-launcher-15-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 82196 INFO (jetty-launcher-15-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 82196 INFO (jetty-launcher-15-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 82196 INFO (jetty-launcher-15-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 82197 INFO (jetty-launcher-15-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@74b7674d{/solr,null,AVAILABLE}
[junit4] 2> 82197 INFO (jetty-launcher-15-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5101122e{/solr,null,AVAILABLE}
[junit4] 2> 82201 INFO (jetty-launcher-15-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4f5f94e2{HTTP/1.1,[http/1.1]}{127.0.0.1:50219}
[junit4] 2> 82201 INFO (jetty-launcher-15-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5bbacee6{HTTP/1.1,[http/1.1]}{127.0.0.1:50220}
[junit4] 2> 82201 INFO (jetty-launcher-15-thread-2) [ ] o.e.j.s.Server Started @82296ms
[junit4] 2> 82201 INFO (jetty-launcher-15-thread-1) [ ] o.e.j.s.Server Started @82296ms
[junit4] 2> 82201 INFO (jetty-launcher-15-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50219}
[junit4] 2> 82201 INFO (jetty-launcher-15-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50220}
[junit4] 2> 82202 ERROR (jetty-launcher-15-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 82202 INFO (jetty-launcher-15-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 82203 INFO (jetty-launcher-15-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 82203 INFO (jetty-launcher-15-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 82203 ERROR (jetty-launcher-15-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 82203 INFO (jetty-launcher-15-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 82203 INFO (jetty-launcher-15-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 82203 INFO (jetty-launcher-15-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-23T21:25:00.106Z
[junit4] 2> 82203 INFO (jetty-launcher-15-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 82203 INFO (jetty-launcher-15-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 82203 INFO (jetty-launcher-15-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 82203 INFO (jetty-launcher-15-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-23T21:25:00.106Z
[junit4] 2> 82208 INFO (zkConnectionManagerCallback-21-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 82210 INFO (zkConnectionManagerCallback-22-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 82221 INFO (jetty-launcher-15-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 82243 INFO (jetty-launcher-15-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 82254 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10019c427ab0002, likely client has closed socket
[junit4] 2> 82287 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10019c427ab0001, likely client has closed socket
[junit4] 2> 82801 INFO (jetty-launcher-15-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50211/solr
[junit4] 2> 82925 INFO (zkConnectionManagerCallback-26-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 82962 INFO (zkConnectionManagerCallback-28-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 83084 INFO (jetty-launcher-15-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50211/solr
[junit4] 2> 83086 INFO (zkConnectionManagerCallback-34-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 83099 INFO (zkConnectionManagerCallback-36-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 83541 INFO (jetty-launcher-15-thread-1) [n:127.0.0.1:50219_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50219_solr
[junit4] 2> 83543 INFO (jetty-launcher-15-thread-2) [n:127.0.0.1:50220_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50220_solr
[junit4] 2> 83543 INFO (jetty-launcher-15-thread-1) [n:127.0.0.1:50219_solr ] o.a.s.c.Overseer Overseer (id=72085924307730436-127.0.0.1:50219_solr-n_0000000000) starting
[junit4] 2> 83586 INFO (zkCallback-35-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 83586 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 83700 INFO (zkConnectionManagerCallback-43-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 83704 INFO (jetty-launcher-15-thread-1) [n:127.0.0.1:50219_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 83708 INFO (jetty-launcher-15-thread-1) [n:127.0.0.1:50219_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50211/solr ready
[junit4] 2> 83829 INFO (zkConnectionManagerCallback-48-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 83833 INFO (jetty-launcher-15-thread-2) [n:127.0.0.1:50220_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 83837 INFO (jetty-launcher-15-thread-2) [n:127.0.0.1:50220_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50211/solr ready
[junit4] 2> 83838 INFO (jetty-launcher-15-thread-2) [n:127.0.0.1:50220_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 84014 INFO (jetty-launcher-15-thread-2) [n:127.0.0.1:50220_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50220.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 84030 INFO (jetty-launcher-15-thread-2) [n:127.0.0.1:50220_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50220.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 84030 INFO (jetty-launcher-15-thread-2) [n:127.0.0.1:50220_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50220.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 84033 INFO (jetty-launcher-15-thread-2) [n:127.0.0.1:50220_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34B4578291158E4C-001/tempDir-001/node2/.
[junit4] 2> 84128 INFO (jetty-launcher-15-thread-1) [n:127.0.0.1:50219_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 84128 INFO (jetty-launcher-15-thread-1) [n:127.0.0.1:50219_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50219_solr
[junit4] 2> 84131 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 84131 INFO (zkCallback-35-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 84132 INFO (zkCallback-47-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 84132 INFO (zkCallback-42-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 84132 INFO (OverseerStateUpdate-72085924307730436-127.0.0.1:50219_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50219_solr
[junit4] 2> 84193 INFO (jetty-launcher-15-thread-1) [n:127.0.0.1:50219_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 84230 INFO (jetty-launcher-15-thread-1) [n:127.0.0.1:50219_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50219.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 84239 INFO (jetty-launcher-15-thread-1) [n:127.0.0.1:50219_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50219.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 84239 INFO (jetty-launcher-15-thread-1) [n:127.0.0.1:50219_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50219.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 84240 INFO (jetty-launcher-15-thread-1) [n:127.0.0.1:50219_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.TriggerIntegrationTest_34B4578291158E4C-001/tempDir-001/node1/.
[junit4] 2> 84286 INFO (zkConnectionManagerCallback-52-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84315 INFO (zkConnectionManagerCallback-57-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 84318 INFO (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 84321 INFO (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50211/solr ready
[junit4] 2> 84454 ERROR (qtp1290720973-186) [n:127.0.0.1:50220_solr ] o.a.s.h.RequestHandlerBase org.apache.solr.api.ApiBag$ExceptionWithErrObject: Error in command payload, errors: [{suspend-trigger={name=.scheduled_maintenance}, errorMessages=[No trigger exists with name: .scheduled_maintenance]}],
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:210)
[junit4] 2> at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:148)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at org.apache.solr.api.ApiBag$ReqHandlerToApi.call(ApiBag.java:242)
[junit4] 2> at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:336)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:715)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:496)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:377)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:323)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1642)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1317)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1219)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:674)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:531)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:352)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:281)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:762)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:680)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 84454 INFO (qtp1290720973-186) [n:127.0.0.1:50220_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=400 QTime=10
[junit4] 2> 84469 INFO (jetty-closer-16-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5bbacee6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 84469 INFO (jetty-closer-16-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4f5f94e2{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 84470 INFO (jetty-closer-16-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=828227462
[junit4] 2> 84470 INFO (jetty-closer-16-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=664459016
[junit4] 2> 84470 INFO (jetty-closer-16-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 84470 INFO (jetty-closer-16-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 84471 INFO (jetty-closer-16-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@37e4e3ab: rootName = solr_50220, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@511bba8c
[junit4] 2> 84471 INFO (jetty-closer-16-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7d853be4: rootName = solr_50219, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@41420320
[junit4] 2> 84477 INFO (jetty-closer-16-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 84478 INFO (jetty-closer-16-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@47b17424: rootName = solr_50219, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@26983b72
[junit4] 2> 84478 INFO (jetty-closer-16-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 84478 INFO (jetty-closer-16-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@41576f5c: rootName = solr_50220, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@26983b72
[junit4] 2> 84481 INFO (jetty-closer-16-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 84481 INFO (jetty-closer-16-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ff9fdee: rootName = solr_50219, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@78a1a429
[junit4] 2> 84482 INFO (jetty-closer-16-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 84482 INFO (jetty-closer-16-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@33262255: rootName = solr_50220, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@78a1a429
[junit4] 2> 84484 INFO (jetty-closer-16-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:50219_solr
[junit4] 2> 84486 INFO (jetty-closer-16-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:50220_solr
[junit4] 2> 84487 INFO (zkCallback-42-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 84487 INFO (zkCallback-35-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 84487 INFO (zkCallback-47-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 84486 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 84488 INFO (jetty-closer-16-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 84492 INFO (zkCallback-35-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 84492 INFO (zkCallback-42-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 84493 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 84493 INFO (zkCallback-47-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 84493 INFO (jetty-closer-16-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 84494 INFO (jetty-closer-16-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 84496 INFO (jetty-closer-16-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72085924307730436-127.0.0.1:50219_solr-n_0000000000) closing
[junit4] 2> 84496 INFO (OverseerStateUpdate-72085924307730436-127.0.0.1:50219_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50219_solr
[junit4] 2> 84497 DEBUG (jetty-closer-16-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 84497 DEBUG (jetty-closer-16-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 84497 DEBUG (jetty-closer-16-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 84497 DEBUG (jetty-closer-16-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 84497 DEBUG (jetty-closer-16-thread-2) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 84497 DEBUG (jetty-closer-16-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 84504 INFO (jetty-closer-16-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5101122e{/solr,null,UNAVAILABLE}
[junit4] 2> 84505 INFO (jetty-closer-16-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 84517 DEBUG (OverseerAutoScalingTriggerThread-72085924307730436-127.0.0.1:50219_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 84523 WARN (OverseerAutoScalingTriggerThread-72085924307730436-127.0.0.1:50219_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Interrupted
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at java.lang.Object.wait(Native Method) ~[?:1.8.0_172]
[junit4] 2> at java.lang.Object.wait(Object.java:502) ~[?:1.8.0_172]
[junit4] 2> at org.apache.zookeeper.ClientCnxn.submitRequest(ClientCnxn.java:1409) ~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.setData(ZooKeeper.java:1328) ~[zookeeper-3.4.11.jar:3.4.11-37e277162d567b55a07d1755f0b31c32e93c01a0]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$setData$6(SolrZkClient.java:353) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.setData(SolrZkClient.java:353) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.ZkDistribStateManager.setData(ZkDistribStateManager.java:146) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:136) [java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 84530 INFO (jetty-closer-16-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@74b7674d{/solr,null,UNAVAILABLE}
[junit4] 2> 84530 INFO (jetty-closer-16-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 84531 ERROR (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 84538 INFO (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:50211 50211
[junit4] 2> 84559 INFO (Thread-52) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:50211 50211
[junit4] 2> 85339 WARN (Thread-52) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> 85340 INFO (SUITE-TriggerIntegrationTest-seed#[34B4578291158E4C]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=23856, maxDocsPerChunk=991, blockSize=2), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=23856, blockSize=2)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5014429e), locale=ca-ES, timezone=Asia/Seoul
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=3,threads=1,free=127745488,total=266338304
[junit4] 2> NOTE: All tests run in this JVM: [SignificantTermsQParserPluginTest, TestRandomFaceting, SolrCmdDistributorTest, TriggerIntegrationTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TriggerIntegrationTest -Dtests.seed=34B4578291158E4C -Dtests.slow=true -Dtests.locale=ca-ES -Dtests.timezone=Asia/Seoul -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TriggerIntegrationTest (suite) <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteExecutionException: Error from server at http://127.0.0.1:50220/solr: Error in command payload
[junit4] > at __randomizedtesting.SeedInfo.seed([34B4578291158E4C]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteExecutionException.create(HttpSolrClient.java:829)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:620)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819)
[junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
[junit4] > at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.setupCluster(TriggerIntegrationTest.java:96)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [9/825 (1!)] on J0 in 4.17s, 0 tests, 1 error <<< FAILURES!
[...truncated 1511 lines...]
[junit4] Suite: org.apache.solr.TestDistributedGrouping
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_34B4578291158E4C-001/init-core-data-001
[junit4] 2> 1795513 WARN (SUITE-TestDistributedGrouping-seed#[34B4578291158E4C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 1795513 INFO (SUITE-TestDistributedGrouping-seed#[34B4578291158E4C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1795519 INFO (SUITE-TestDistributedGrouping-seed#[34B4578291158E4C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1795519 INFO (SUITE-TestDistributedGrouping-seed#[34B4578291158E4C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1795519 INFO (SUITE-TestDistributedGrouping-seed#[34B4578291158E4C]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_xy/
[junit4] 2> 1795943 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_34B4578291158E4C-001/tempDir-001/control/cores/collection1
[junit4] 2> 1795945 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1795946 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1795947 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1795947 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1795952 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d21be8c{/_xy,null,AVAILABLE}
[junit4] 2> 1795953 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4d1fde1f{HTTP/1.1,[http/1.1]}{127.0.0.1:57452}
[junit4] 2> 1795953 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.Server Started @1796048ms
[junit4] 2> 1795953 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_xy, hostPort=57452, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_34B4578291158E4C-001/tempDir-001/control/cores}
[junit4] 2> 1795953 ERROR (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1795953 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1795953 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1795953 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1795953 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1795953 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-23T21:53:33.856Z
[junit4] 2> 1795954 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_34B4578291158E4C-001/tempDir-001/control/solr.xml
[junit4] 2> 1795963 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1795963 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1795964 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1796299 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1796299 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1796321 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 1796334 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 1796334 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 1796336 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_34B4578291158E4C-001/tempDir-001/control/cores
[junit4] 2> 1796336 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1796337 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1796382 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1796399 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1796494 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1796501 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1796502 INFO (coreLoadExecutor-7869-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@7dfb1407
[junit4] 2> 1796502 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1796502 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_34B4578291158E4C-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 1796503 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=30.962890625, floorSegmentMB=1.3359375, forceMergeDeletesPctAllowed=11.0942807772048, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14974482748772797, deletesPctAllowed=34.128996819461975
[junit4] 2> 1796604 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1796604 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1796608 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1796608 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1796609 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7248334775715719]
[junit4] 2> 1796609 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7c1631a8[collection1] main]
[junit4] 2> 1796610 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1796615 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1796631 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1796631 INFO (coreLoadExecutor-7869-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1606819842132803584
[junit4] 2> 1796631 INFO (searcherExecutor-7870-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1796631 INFO (searcherExecutor-7870-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1796631 INFO (searcherExecutor-7870-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1796632 INFO (searcherExecutor-7870-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1796632 INFO (searcherExecutor-7870-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1796632 INFO (searcherExecutor-7870-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1796633 INFO (searcherExecutor-7870-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1796633 INFO (searcherExecutor-7870-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1796633 INFO (searcherExecutor-7870-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1796634 INFO (searcherExecutor-7870-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7c1631a8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1796982 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_34B4578291158E4C-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1796984 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1796985 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1796985 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1796985 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1796985 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@60680cdc{/_xy,null,AVAILABLE}
[junit4] 2> 1796985 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@478b23de{HTTP/1.1,[http/1.1]}{127.0.0.1:57459}
[junit4] 2> 1796985 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.e.j.s.Server Started @1797080ms
[junit4] 2> 1796985 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_xy, hostPort=57459, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_34B4578291158E4C-001/tempDir-001/shard0/cores}
[junit4] 2> 1796986 ERROR (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1796986 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1796986 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1796986 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1796986 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1796986 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-23T21:53:34.889Z
[junit4] 2> 1796987 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_34B4578291158E4C-001/tempDir-001/shard0/solr.xml
[junit4] 2> 1796993 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1796993 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1796994 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1797178 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1797178 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1797201 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 1797208 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 1797208 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dfb1407
[junit4] 2> 1797210 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_34B4578291158E4C-001/tempDir-001/shard0/cores
[junit4] 2> 1797211 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1797211 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1797257 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1797273 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1797360 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1797367 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1797367 INFO (coreLoadExecutor-7883-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@7dfb1407
[junit4] 2> 1797367 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1797367 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_34B4578291158E4C-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 1797369 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=30.962890625, floorSegmentMB=1.3359375, forceMergeDeletesPctAllowed=11.0942807772048, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14974482748772797, deletesPctAllowed=34.128996819461975
[junit4] 2> 1797452 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1797452 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1797454 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1797454 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1797455 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7248334775715719]
[junit4] 2> 1797455 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e7ce2a0[collection1] main]
[junit4] 2> 1797456 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1797459 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1797489 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1797490 INFO (searcherExecutor-7884-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1797490 INFO (coreLoadExecutor-7883-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1606819843033530368
[junit4] 2> 1797490 INFO (searcherExecutor-7884-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1797490 INFO (searcherExecutor-7884-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1797490 INFO (searcherExecutor-7884-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1797490 INFO (searcherExecutor-7884-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1797491 INFO (searcherExecutor-7884-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1797491 INFO (searcherExecutor-7884-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1797491 INFO (searcherExecutor-7884-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1797492 INFO (searcherExecutor-7884-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1797492 INFO (searcherExecutor-7884-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2e7ce2a0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1797493 INFO (TEST-TestDistributedGrouping.test-seed#[34B4578291158E4C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1797496 INFO (qtp1889961621-16446) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1606819843038773248)} 0 1
[junit4] 2> 1797501 INFO (qtp160364713-16466) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1606819843042967552)} 0 1
[junit4] 2> 1797502 INFO (qtp1889961621-16442) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1606819843046113280,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1797503 INFO (qtp1889961621-16442) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1797503 INFO (qtp1889961621-16442) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1797503 INFO (qtp1889961621-16442) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
[junit4] 2> 1797506 INFO (qtp160364713-16462) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1606819843050307584,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1797506 INFO (qtp160364713-16462) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1797506 INFO (qtp160364713-16462) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1797506 INFO (qtp160364713-16462) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
[junit4] 2> 1797507 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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> 1797510 INFO (qtp160364713-16467) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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=http://127.0.0.1:57459/_xy/collection1&rows=100&version=2&q=*:*&NOW=1532382815411&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1797511 INFO (qtp160364713-16465) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy path=/select params={q=*:*&shards=http://127.0.0.1:57459/_xy/collection1&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=2
[junit4] 2> 1797512 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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> 1797513 INFO (qtp160364713-16465) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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=http://127.0.0.1:57459/_xy/collection1|[ff01::083]:33332/_xy|[ff01::213]:33332/_xy|[ff01::114]:33332/_xy&rows=100&version=2&q=*:*&NOW=1532382815415&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1797514 INFO (qtp160364713-16468) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy path=/select params={q=*:*&shards=http://127.0.0.1:57459/_xy/collection1|[ff01::083]:33332/_xy|[ff01::213]:33332/_xy|[ff01::114]:33332/_xy&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=1
[junit4] 2> 1797514 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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> 1797517 INFO (qtp160364713-16468) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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/_xy|[ff01::083]:33332/_xy|http://127.0.0.1:57459/_xy/collection1&rows=100&version=2&q=*:*&NOW=1532382815418&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1797518 INFO (qtp160364713-16465) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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/_xy|[ff01::083]:33332/_xy|http://127.0.0.1:57459/_xy/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1532382815418&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1797519 INFO (qtp160364713-16467) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy path=/select params={q=*:*&shards=[ff01::114]:33332/_xy|[ff01::083]:33332/_xy|http://127.0.0.1:57459/_xy/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=3
[junit4] 2> 1797519 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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> 1797521 INFO (qtp160364713-16467) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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=[ff01::114]:33332/_xy|http://127.0.0.1:57459/_xy/collection1|[ff01::083]:33332/_xy|[ff01::213]:33332/_xy&rows=100&version=2&q=*:*&stats=false&NOW=1532382815423&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0
[junit4] 2> 1797522 INFO (qtp160364713-16467) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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=[ff01::114]:33332/_xy|http://127.0.0.1:57459/_xy/collection1|[ff01::083]:33332/_xy|[ff01::213]:33332/_xy&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1532382815423&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0
[junit4] 2> 1797522 INFO (qtp160364713-16468) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy path=/select params={q=*:*&shards=[ff01::114]:33332/_xy|http://127.0.0.1:57459/_xy/collection1|[ff01::083]:33332/_xy|[ff01::213]:33332/_xy&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> 1797523 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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> 1797524 INFO (qtp160364713-16468) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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=http://127.0.0.1:57459/_xy/collection1&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&NOW=1532382815426&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1797525 INFO (qtp160364713-16467) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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=http://127.0.0.1:57459/_xy/collection1&spellcheck=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=1
[junit4] 2> 1797525 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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=0
[junit4] 2> 1797528 INFO (qtp160364713-16467) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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=[ff01::114]:33332/_xy|http://127.0.0.1:57459/_xy/collection1&rows=100&version=2&q=*:*&group.truncate=true&NOW=1532382815430&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1797529 INFO (qtp160364713-16467) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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=[ff01::114]:33332/_xy|http://127.0.0.1:57459/_xy/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&group.truncate=true&NOW=1532382815430&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1797529 INFO (qtp160364713-16468) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_xy 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=[ff01::114]:33332/_xy|http://127.0.0.1:57459/_xy/collection1&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=2
[junit4] 2> 1797531 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[1 (1606819843075473408)]} 0 1
[junit4] 2> 1797533 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[1 (1606819843077570560)]} 0 1
[junit4] 2> 1797534 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[2 (1606819843079667712)]} 0 0
[junit4] 2> 1797534 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[2 (1606819843079667712)]} 0 0
[junit4] 2> 1797535 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[3 (1606819843080716288)]} 0 0
[junit4] 2> 1797536 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[3 (1606819843081764864)]} 0 0
[junit4] 2> 1797537 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[4 (1606819843081764864)]} 0 0
[junit4] 2> 1797537 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[4 (1606819843082813440)]} 0 0
[junit4] 2> 1797538 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[5 (1606819843083862016)]} 0 0
[junit4] 2> 1797539 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[5 (1606819843083862016)]} 0 0
[junit4] 2> 1797539 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[6 (1606819843084910592)]} 0 0
[junit4] 2> 1797540 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[6 (1606819843085959168)]} 0 0
[junit4] 2> 1797540 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[7 (1606819843085959168)]} 0 0
[junit4] 2> 1797541 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[7 (1606819843087007744)]} 0 0
[junit4] 2> 1797542 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[8 (1606819843088056320)]} 0 0
[junit4] 2> 1797542 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[8 (1606819843088056320)]} 0 0
[junit4] 2> 1797543 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[9 (1606819843089104896)]} 0 0
[junit4] 2> 1797544 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[9 (1606819843090153472)]} 0 0
[junit4] 2> 1797544 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[10 (1606819843090153472)]} 0 0
[junit4] 2> 1797545 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[10 (1606819843091202048)]} 0 0
[junit4] 2> 1797546 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[11 (1606819843092250624)]} 0 0
[junit4] 2> 1797547 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[11 (1606819843092250624)]} 0 0
[junit4] 2> 1797547 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[12 (1606819843093299200)]} 0 0
[junit4] 2> 1797548 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[12 (1606819843094347776)]} 0 0
[junit4] 2> 1797549 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[14 (1606819843094347776)]} 0 0
[junit4] 2> 1797550 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[14 (1606819843095396352)]} 0 0
[junit4] 2> 1797551 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[15 (1606819843097493504)]} 0 0
[junit4] 2> 1797552 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[15 (1606819843097493504)]} 0 0
[junit4] 2> 1797552 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[16 (1606819843098542080)]} 0 0
[junit4] 2> 1797553 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[16 (1606819843099590656)]} 0 0
[junit4] 2> 1797554 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[17 (1606819843099590656)]} 0 0
[junit4] 2> 1797554 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[17 (1606819843100639232)]} 0 0
[junit4] 2> 1797555 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[18 (1606819843101687808)]} 0 0
[junit4] 2> 1797556 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[18 (1606819843101687808)]} 0 0
[junit4] 2> 1797556 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[19 (1606819843102736384)]} 0 0
[junit4] 2> 1797557 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[19 (1606819843103784960)]} 0 0
[junit4] 2> 1797557 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[20 (1606819843103784960)]} 0 0
[junit4] 2> 1797558 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[20 (1606819843104833536)]} 0 0
[junit4] 2> 1797559 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[21 (1606819843104833536)]} 0 0
[junit4] 2> 1797559 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[21 (1606819843105882112)]} 0 0
[junit4] 2> 1797560 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[22 (1606819843106930688)]} 0 0
[junit4] 2> 1797560 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[22 (1606819843106930688)]} 0 0
[junit4] 2> 1797561 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[23 (1606819843107979264)]} 0 0
[junit4] 2> 1797561 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[23 (1606819843107979264)]} 0 0
[junit4] 2> 1797562 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[100 (1606819843109027840)]} 0 0
[junit4] 2> 1797562 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[100 (1606819843109027840)]} 0 0
[junit4] 2> 1797563 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[101 (1606819843110076416)]} 0 0
[junit4] 2> 1797563 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[101 (1606819843110076416)]} 0 0
[junit4] 2> 1797564 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[102 (1606819843111124992)]} 0 0
[junit4] 2> 1797564 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[102 (1606819843111124992)]} 0 0
[junit4] 2> 1797565 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[103 (1606819843112173568)]} 0 0
[junit4] 2> 1797565 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[103 (1606819843112173568)]} 0 0
[junit4] 2> 1797566 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[104 (1606819843113222144)]} 0 0
[junit4] 2> 1797566 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[104 (1606819843113222144)]} 0 0
[junit4] 2> 1797567 INFO (qtp1889961621-16445) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[105 (1606819843114270720)]} 0 0
[junit4] 2> 1797567 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[105 (1606819843114270720)]} 0 0
[junit4] 2> 1797568 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[106 (1606819843115319296)]} 0 0
[junit4] 2> 1797568 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[106 (1606819843115319296)]} 0 0
[junit4] 2> 1797569 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[107 (1606819843116367872)]} 0 0
[junit4] 2> 1797569 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[107 (1606819843116367872)]} 0 0
[junit4] 2> 1797570 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[108 (1606819843117416448)]} 0 0
[junit4] 2> 1797570 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[108 (1606819843117416448)]} 0 0
[junit4] 2> 1797571 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[109 (1606819843118465024)]} 0 0
[junit4] 2> 1797572 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[109 (1606819843118465024)]} 0 0
[junit4] 2> 1797573 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[110 (1606819843120562176)]} 0 0
[junit4] 2> 1797573 INFO (qtp160364713-16467) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[110 (1606819843120562176)]} 0 0
[junit4] 2> 1797574 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[111 (1606819843121610752)]} 0 0
[junit4] 2> 1797575 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[111 (1606819843122659328)]} 0 0
[junit4] 2> 1797575 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[112 (1606819843122659328)]} 0 0
[junit4] 2> 1797576 INFO (qtp160364713-16468) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[112 (1606819843123707904)]} 0 0
[junit4] 2> 1797576 INFO (qtp1889961621-16447) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_xy path=/update params={wt=javabin&version=2}{add=[113 (1606819843123707904)]}
[...truncated too long message...]
lrj/lib/httpmime-4.5.3.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/solrj/lib/jcl-over-slf4j-1.7.24.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/solrj/lib/noggit-0.8.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/solrj/lib/slf4j-api-1.7.24.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/solrj/lib/stax2-api-3.1.4.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/solrj/lib/woodstox-core-asl-4.4.1.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/solrj/lib/zookeeper-3.4.11.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/gmetric4j-1.0.7.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/javax.servlet-api-3.1.0.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-continuation-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-deploy-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-http-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-io-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-jmx-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-rewrite-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-security-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-server-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-servlet-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-servlets-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-util-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-webapp-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/jetty-xml-9.4.11.v20180605.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/metrics-core-3.2.6.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/metrics-ganglia-3.2.6.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/metrics-graphite-3.2.6.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/metrics-jetty9-3.2.6.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/lib/metrics-jvm-3.2.6.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/example/example-DIH/solr/db/lib/hsqldb-2.4.0.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/argparse4j-0.8.1.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/jackson-annotations-2.9.5.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/jackson-core-2.9.5.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/jackson-databind-2.9.5.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/jackson-jq-0.0.8.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/log4j-api-2.11.0.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/log4j-core-2.11.0.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/log4j-slf4j-impl-2.11.0.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/simpleclient-0.2.0.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/simpleclient_common-0.2.0.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/simpleclient_httpserver-0.2.0.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/contrib/prometheus-exporter/lib/slf4j-api-1.7.24.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/build/core/classes/java:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/test-framework/lib/junit-4.10.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/test-framework/lib/randomizedtesting-runner-2.6.0.jar:/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-prometheus-exporter/classes/java:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/Users/jenkins/.ant/lib/ivy-2.4.0.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/Library/Java/JavaVirtualMachines/jdk1.8.0_172.jdk/Contents/Home/lib/tools.jar:/Users/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.6.0.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-prometheus-exporter/test/temp/junit4-J0-20180723_223556_330758154826686034283.events @/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-prometheus-exporter/test/temp/junit4-J0-20180723_223556_3307698389743512078993.suites -stdin
[junit4] ERROR: JVM J0 ended with an exception: Forked process returned with error code: 134. Very likely a JVM crash. See process stdout at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-prometheus-exporter/test/temp/junit4-J0-20180723_223556_330269525323427416168.sysout
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1519)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:126)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:982)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:979)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] at java.lang.Thread.run(Thread.java:748)
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:633: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:577: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:59: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build.xml:287: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/common-build.xml:477: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/common-build.xml:556: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1568: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1092: At least one slave process threw an exception, first: Forked process returned with error code: 134. Very likely a JVM crash. See process stdout at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/contrib/solr-prometheus-exporter/test/temp/junit4-J0-20180723_223556_330269525323427416168.sysout
Total time: 104 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 4753
- Still unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4753/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
5 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([AA8A789492496763:22DE474E3CB50A9B]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: 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([AA8A789492496763:22DE474E3CB50A9B]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: 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([AA8A789492496763:22DE474E3CB50A9B]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: 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([AA8A789492496763:22DE474E3CB50A9B]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: 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([AA8A789492496763:22DE474E3CB50A9B]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13571 lines...]
[junit4] Suite: org.apache.solr.TestDistributedGrouping
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-001/init-core-data-001
[junit4] 2> 1630819 WARN (SUITE-TestDistributedGrouping-seed#[AA8A789492496763]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 1630820 INFO (SUITE-TestDistributedGrouping-seed#[AA8A789492496763]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1630830 INFO (SUITE-TestDistributedGrouping-seed#[AA8A789492496763]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1630830 INFO (SUITE-TestDistributedGrouping-seed#[AA8A789492496763]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1630830 INFO (SUITE-TestDistributedGrouping-seed#[AA8A789492496763]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /bng/p
[junit4] 2> 1631279 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-001/tempDir-001/control/cores/collection1
[junit4] 2> 1631280 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1631282 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1631282 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1631282 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1631286 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6c146774{/bng/p,null,AVAILABLE}
[junit4] 2> 1631292 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@60320ff9{HTTP/1.1,[http/1.1]}{127.0.0.1:60497}
[junit4] 2> 1631292 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.Server Started @1631381ms
[junit4] 2> 1631292 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/bng/p, hostPort=60497, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-001/tempDir-001/control/cores}
[junit4] 2> 1631292 ERROR (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1631292 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1631292 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1631292 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1631292 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1631292 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-24T08:40:46.704Z
[junit4] 2> 1631293 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-001/tempDir-001/control/solr.xml
[junit4] 2> 1631300 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1631301 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1631301 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6a81b79e, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1631686 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1631686 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1631717 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a81b79e
[junit4] 2> 1631730 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a81b79e
[junit4] 2> 1631730 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a81b79e
[junit4] 2> 1631733 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-001/tempDir-001/control/cores
[junit4] 2> 1631733 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1631734 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1631799 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1631842 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1631927 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1631940 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1631940 INFO (coreLoadExecutor-6204-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@6a81b79e
[junit4] 2> 1631941 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1631941 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 1631942 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=38, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1632074 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1632074 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1632076 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1632076 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1632076 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=16.0908203125, floorSegmentMB=1.4130859375, forceMergeDeletesPctAllowed=9.995874567113667, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=22.955376398902196
[junit4] 2> 1632077 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@54d3dd05[collection1] main]
[junit4] 2> 1632078 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1632211 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1632481 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1632481 INFO (searcherExecutor-6205-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1632481 INFO (coreLoadExecutor-6204-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1606860561861050368
[junit4] 2> 1632484 INFO (searcherExecutor-6205-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1632485 INFO (searcherExecutor-6205-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1632485 INFO (searcherExecutor-6205-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1632486 INFO (searcherExecutor-6205-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1632487 INFO (searcherExecutor-6205-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1632487 INFO (searcherExecutor-6205-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1632488 INFO (searcherExecutor-6205-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1632488 INFO (searcherExecutor-6205-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1632489 INFO (searcherExecutor-6205-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@54d3dd05[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1632888 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1632889 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
[junit4] 2> 1632891 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1632891 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1632891 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1632891 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6588287e{/bng/p,null,AVAILABLE}
[junit4] 2> 1632892 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4170bbaa{HTTP/1.1,[http/1.1]}{127.0.0.1:60498}
[junit4] 2> 1632894 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.Server Started @1632983ms
[junit4] 2> 1632894 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/bng/p, hostPort=60498, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-001/tempDir-001/shard0/cores}
[junit4] 2> 1632894 ERROR (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1632894 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1632894 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1632894 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1632894 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1632894 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-24T08:40:48.306Z
[junit4] 2> 1632895 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-001/tempDir-001/shard0/solr.xml
[junit4] 2> 1632902 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1632902 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1632903 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6a81b79e, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1633176 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1633176 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1633206 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a81b79e
[junit4] 2> 1633218 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a81b79e
[junit4] 2> 1633219 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6a81b79e
[junit4] 2> 1633222 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-001/tempDir-001/shard0/cores
[junit4] 2> 1633222 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1633223 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1633334 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1633426 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1633541 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1633554 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1633554 INFO (coreLoadExecutor-6218-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@6a81b79e
[junit4] 2> 1633554 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1633555 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 1633556 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=38, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1633677 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1633677 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1633678 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1633678 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1633679 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=16.0908203125, floorSegmentMB=1.4130859375, forceMergeDeletesPctAllowed=9.995874567113667, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=22.955376398902196
[junit4] 2> 1633679 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7982a5a3[collection1] main]
[junit4] 2> 1633680 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1633698 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1633745 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1633745 INFO (coreLoadExecutor-6218-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1606860563186450432
[junit4] 2> 1633745 INFO (searcherExecutor-6219-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1633750 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1633751 INFO (searcherExecutor-6219-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1633751 INFO (searcherExecutor-6219-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1633751 INFO (searcherExecutor-6219-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1633754 INFO (searcherExecutor-6219-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1633764 INFO (searcherExecutor-6219-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1633764 INFO (qtp970973590-16323) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1606860563193790464)} 0 13
[junit4] 2> 1633765 INFO (searcherExecutor-6219-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1633768 INFO (searcherExecutor-6219-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1633776 INFO (searcherExecutor-6219-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1633777 INFO (searcherExecutor-6219-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7982a5a3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1633786 INFO (qtp1470901867-16343) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1606860563208470528)} 0 20
[junit4] 2> 1633795 INFO (qtp970973590-16319) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1606860563238879232,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1633795 INFO (qtp970973590-16319) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1633797 INFO (qtp970973590-16319) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1633797 INFO (qtp970973590-16319) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 10
[junit4] 2> 1633805 INFO (qtp1470901867-16339) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1606860563249364992,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1633805 INFO (qtp1470901867-16339) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1633807 INFO (qtp1470901867-16339) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1633807 INFO (qtp1470901867-16339) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 9
[junit4] 2> 1633812 INFO (qtp970973590-16322) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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=4
[junit4] 2> 1633816 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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=[ff01::213]:33332/bng/p|http://127.0.0.1:60498/bng/p/collection1&rows=100&version=2&q=*:*&NOW=1532421649225&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1633817 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p path=/select params={q=*:*&shards=[ff01::213]:33332/bng/p|http://127.0.0.1:60498/bng/p/collection1&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=3
[junit4] 2> 1633819 INFO (qtp970973590-16324) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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> 1633821 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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::083]:33332/bng/p|http://127.0.0.1:60498/bng/p/collection1|[ff01::114]:33332/bng/p&rows=100&version=2&q=*:*&NOW=1532421649231&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1633821 INFO (qtp1470901867-16345) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p path=/select params={q=*:*&shards=[ff01::083]:33332/bng/p|http://127.0.0.1:60498/bng/p/collection1|[ff01::114]:33332/bng/p&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> 1633822 INFO (qtp970973590-16322) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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> 1633824 INFO (qtp1470901867-16345) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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::083]:33332/bng/p|[ff01::213]:33332/bng/p|http://127.0.0.1:60498/bng/p/collection1&rows=100&version=2&q=*:*&NOW=1532421649235&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1633825 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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::083]:33332/bng/p|[ff01::213]:33332/bng/p|http://127.0.0.1:60498/bng/p/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1532421649235&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1633826 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p path=/select params={q=*:*&shards=[ff01::083]:33332/bng/p|[ff01::213]:33332/bng/p|http://127.0.0.1:60498/bng/p/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=2
[junit4] 2> 1633827 INFO (qtp970973590-16324) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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> 1633828 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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=http://127.0.0.1:60498/bng/p/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1532421649240&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0
[junit4] 2> 1633829 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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=http://127.0.0.1:60498/bng/p/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1532421649240&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0
[junit4] 2> 1633830 INFO (qtp1470901867-16345) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p path=/select params={q=*:*&shards=http://127.0.0.1:60498/bng/p/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> 1633831 INFO (qtp970973590-16322) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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> 1633832 INFO (qtp1470901867-16345) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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::213]:33332/bng/p|http://127.0.0.1:60498/bng/p/collection1&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&NOW=1532421649243&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1633833 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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::213]:33332/bng/p|http://127.0.0.1:60498/bng/p/collection1&spellcheck=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=1
[junit4] 2> 1633847 INFO (qtp970973590-16324) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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=14
[junit4] 2> 1633849 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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=http://127.0.0.1:60498/bng/p/collection1&rows=100&version=2&q=*:*&group.truncate=true&NOW=1532421649260&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1633850 INFO (qtp1470901867-16345) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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=http://127.0.0.1:60498/bng/p/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&group.truncate=true&NOW=1532421649260&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0
[junit4] 2> 1633851 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/bng/p 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=http://127.0.0.1:60498/bng/p/collection1&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=2
[junit4] 2> 1633854 INFO (qtp970973590-16322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[1 (1606860563298648064)]} 0 2
[junit4] 2> 1633856 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[1 (1606860563301793792)]} 0 1
[junit4] 2> 1633858 INFO (qtp970973590-16322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[2 (1606860563303890944)]} 0 1
[junit4] 2> 1633859 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[2 (1606860563305988096)]} 0 0
[junit4] 2> 1633861 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[3 (1606860563308085248)]} 0 1
[junit4] 2> 1633863 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[3 (1606860563309133824)]} 0 0
[junit4] 2> 1633863 INFO (qtp970973590-16322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[4 (1606860563310182400)]} 0 0
[junit4] 2> 1633864 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[4 (1606860563311230976)]} 0 0
[junit4] 2> 1633865 INFO (qtp970973590-16322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[5 (1606860563312279552)]} 0 0
[junit4] 2> 1633867 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[5 (1606860563313328128)]} 0 1
[junit4] 2> 1633869 INFO (qtp970973590-16322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[6 (1606860563315425280)]} 0 1
[junit4] 2> 1633870 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[6 (1606860563317522432)]} 0 0
[junit4] 2> 1633871 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[7 (1606860563318571008)]} 0 0
[junit4] 2> 1633872 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[7 (1606860563318571008)]} 0 0
[junit4] 2> 1633873 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[8 (1606860563319619584)]} 0 0
[junit4] 2> 1633874 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[8 (1606860563320668160)]} 0 0
[junit4] 2> 1633874 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[9 (1606860563321716736)]} 0 0
[junit4] 2> 1633875 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[9 (1606860563322765312)]} 0 0
[junit4] 2> 1633876 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[10 (1606860563323813888)]} 0 0
[junit4] 2> 1633877 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[10 (1606860563324862464)]} 0 0
[junit4] 2> 1633886 INFO (qtp970973590-16322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[11 (1606860563325911040)]} 0 8
[junit4] 2> 1633887 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[11 (1606860563335348224)]} 0 0
[junit4] 2> 1633888 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[12 (1606860563336396800)]} 0 0
[junit4] 2> 1633889 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[12 (1606860563336396800)]} 0 0
[junit4] 2> 1633892 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[14 (1606860563337445376)]} 0 2
[junit4] 2> 1633895 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[14 (1606860563340591104)]} 0 2
[junit4] 2> 1633896 INFO (qtp970973590-16322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[15 (1606860563343736832)]} 0 0
[junit4] 2> 1633896 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[15 (1606860563344785408)]} 0 0
[junit4] 2> 1633898 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[16 (1606860563346882560)]} 0 0
[junit4] 2> 1633899 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[16 (1606860563347931136)]} 0 0
[junit4] 2> 1633900 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[17 (1606860563347931136)]} 0 0
[junit4] 2> 1633900 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[17 (1606860563348979712)]} 0 0
[junit4] 2> 1633901 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[18 (1606860563350028288)]} 0 0
[junit4] 2> 1633902 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[18 (1606860563351076864)]} 0 0
[junit4] 2> 1633903 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[19 (1606860563352125440)]} 0 0
[junit4] 2> 1633903 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[19 (1606860563352125440)]} 0 0
[junit4] 2> 1633904 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[20 (1606860563353174016)]} 0 0
[junit4] 2> 1633905 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[20 (1606860563354222592)]} 0 0
[junit4] 2> 1633906 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[21 (1606860563354222592)]} 0 0
[junit4] 2> 1633906 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[21 (1606860563355271168)]} 0 0
[junit4] 2> 1633907 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[22 (1606860563356319744)]} 0 0
[junit4] 2> 1633908 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[22 (1606860563357368320)]} 0 0
[junit4] 2> 1633909 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[23 (1606860563358416896)]} 0 0
[junit4] 2> 1633910 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[23 (1606860563358416896)]} 0 0
[junit4] 2> 1633910 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[100 (1606860563359465472)]} 0 0
[junit4] 2> 1633911 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[100 (1606860563360514048)]} 0 0
[junit4] 2> 1633912 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[101 (1606860563360514048)]} 0 0
[junit4] 2> 1633912 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[101 (1606860563361562624)]} 0 0
[junit4] 2> 1633913 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[102 (1606860563362611200)]} 0 0
[junit4] 2> 1633913 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[102 (1606860563362611200)]} 0 0
[junit4] 2> 1633914 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[103 (1606860563363659776)]} 0 0
[junit4] 2> 1633915 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[103 (1606860563363659776)]} 0 0
[junit4] 2> 1633915 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[104 (1606860563364708352)]} 0 0
[junit4] 2> 1633916 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[104 (1606860563365756928)]} 0 0
[junit4] 2> 1633917 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[105 (1606860563365756928)]} 0 0
[junit4] 2> 1633917 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[105 (1606860563366805504)]} 0 0
[junit4] 2> 1633918 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[106 (1606860563367854080)]} 0 0
[junit4] 2> 1633918 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[106 (1606860563367854080)]} 0 0
[junit4] 2> 1633919 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[107 (1606860563368902656)]} 0 0
[junit4] 2> 1633919 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[107 (1606860563368902656)]} 0 0
[junit4] 2> 1633920 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[108 (1606860563369951232)]} 0 0
[junit4] 2> 1633921 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[108 (1606860563369951232)]} 0 0
[junit4] 2> 1633921 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[109 (1606860563370999808)]} 0 0
[junit4] 2> 1633922 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[109 (1606860563372048384)]} 0 0
[junit4] 2> 1633922 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[110 (1606860563372048384)]} 0 0
[junit4] 2> 1633923 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[110 (1606860563373096960)]} 0 0
[junit4] 2> 1633924 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[111 (1606860563374145536)]} 0 0
[junit4] 2> 1633924 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[111 (1606860563374145536)]} 0 0
[junit4] 2> 1633925 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[112 (1606860563375194112)]} 0 0
[junit4] 2> 1633926 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[112 (1606860563375194112)]} 0 0
[junit4] 2> 1633926 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[113 (1606860563376242688)]} 0 0
[junit4] 2> 1633927 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[113 (1606860563377291264)]} 0 0
[junit4] 2> 1633928 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[114 (1606860563377291264)]} 0 0
[junit4] 2> 1633928 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[114 (1606860563378339840)]} 0 0
[junit4] 2> 1633929 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[115 (1606860563379388416)]} 0 0
[junit4] 2> 1633930 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[115 (1606860563379388416)]} 0 0
[junit4] 2> 1633930 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[116 (1606860563380436992)]} 0 0
[junit4] 2> 1633931 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[116 (1606860563381485568)]} 0 0
[junit4] 2> 1633931 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[117 (1606860563381485568)]} 0 0
[junit4] 2> 1633932 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[117 (1606860563382534144)]} 0 0
[junit4] 2> 1633933 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[118 (1606860563382534144)]} 0 0
[junit4] 2> 1633933 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[118 (1606860563383582720)]} 0 0
[junit4] 2> 1633934 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[119 (1606860563384631296)]} 0 0
[junit4] 2> 1633935 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[119 (1606860563385679872)]} 0 0
[junit4] 2> 1633936 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[120 (1606860563386728448)]} 0 0
[junit4] 2> 1633936 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[120 (1606860563386728448)]} 0 0
[junit4] 2> 1633937 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[121 (1606860563387777024)]} 0 0
[junit4] 2> 1633938 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[121 (1606860563388825600)]} 0 0
[junit4] 2> 1633938 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[122 (1606860563388825600)]} 0 0
[junit4] 2> 1633939 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[122 (1606860563389874176)]} 0 0
[junit4] 2> 1633939 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[123 (1606860563389874176)]} 0 0
[junit4] 2> 1633940 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[123 (1606860563390922752)]} 0 0
[junit4] 2> 1633941 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[124 (1606860563391971328)]} 0 0
[junit4] 2> 1633941 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[124 (1606860563391971328)]} 0 0
[junit4] 2> 1633942 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[125 (1606860563393019904)]} 0 0
[junit4] 2> 1633943 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[125 (1606860563393019904)]} 0 0
[junit4] 2> 1633943 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[126 (1606860563394068480)]} 0 0
[junit4] 2> 1633944 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[126 (1606860563395117056)]} 0 0
[junit4] 2> 1633945 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[127 (1606860563395117056)]} 0 0
[junit4] 2> 1633945 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[127 (1606860563396165632)]} 0 0
[junit4] 2> 1633946 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[128 (1606860563397214208)]} 0 0
[junit4] 2> 1633947 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[128 (1606860563398262784)]} 0 0
[junit4] 2> 1633947 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[129 (1606860563398262784)]} 0 0
[junit4] 2> 1633948 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[129 (1606860563399311360)]} 0 0
[junit4] 2> 1633949 INFO (qtp970973590-16322) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[130 (1606860563400359936)]} 0 0
[junit4] 2> 1633949 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[130 (1606860563400359936)]} 0 0
[junit4] 2> 1633950 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[131 (1606860563401408512)]} 0 0
[junit4] 2> 1633951 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[131 (1606860563402457088)]} 0 0
[junit4] 2> 1633951 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[132 (1606860563402457088)]} 0 0
[junit4] 2> 1633952 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[132 (1606860563403505664)]} 0 0
[junit4] 2> 1633953 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[133 (1606860563404554240)]} 0 0
[junit4] 2> 1633953 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[133 (1606860563404554240)]} 0 0
[junit4] 2> 1633954 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[134 (1606860563405602816)]} 0 0
[junit4] 2> 1633955 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[134 (1606860563405602816)]} 0 0
[junit4] 2> 1633955 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[135 (1606860563406651392)]} 0 0
[junit4] 2> 1633956 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[135 (1606860563407699968)]} 0 0
[junit4] 2> 1633956 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[136 (1606860563407699968)]} 0 0
[junit4] 2> 1633957 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[136 (1606860563408748544)]} 0 0
[junit4] 2> 1633958 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[137 (1606860563409797120)]} 0 0
[junit4] 2> 1633958 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[137 (1606860563409797120)]} 0 0
[junit4] 2> 1633959 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[138 (1606860563410845696)]} 0 0
[junit4] 2> 1633960 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[138 (1606860563410845696)]} 0 0
[junit4] 2> 1633960 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[139 (1606860563411894272)]} 0 0
[junit4] 2> 1633961 INFO (qtp1470901867-16344) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[139 (1606860563412942848)]} 0 0
[junit4] 2> 1633961 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[140 (1606860563412942848)]} 0 0
[junit4] 2> 1633962 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[140 (1606860563413991424)]} 0 0
[junit4] 2> 1633963 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[141 (1606860563415040000)]} 0 0
[junit4] 2> 1633963 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[141 (1606860563415040000)]} 0 0
[junit4] 2> 1633964 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[142 (1606860563416088576)]} 0 0
[junit4] 2> 1633965 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[142 (1606860563417137152)]} 0 0
[junit4] 2> 1633965 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[143 (1606860563417137152)]} 0 0
[junit4] 2> 1633966 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[143 (1606860563418185728)]} 0 0
[junit4] 2> 1633967 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[144 (1606860563419234304)]} 0 0
[junit4] 2> 1633967 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[144 (1606860563419234304)]} 0 0
[junit4] 2> 1633968 INFO (qtp970973590-16324) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/bng/p path=/update params={wt=javabin&version=2}{add=[145 (1606860563420282880)]} 0 0
[junit4] 2> 1633969 INFO (qtp1470901867-16342) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection
[...truncated too long message...]
TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2215f1c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 49094 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1972119053
[junit4] 2> 49094 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 49094 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@17dca376: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@357fe137
[junit4] 2> 49103 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 49104 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3cce491b: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3dd1e1d1
[junit4] 2> 49108 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 49108 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@462a1cd4: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7857373f
[junit4] 2> 49110 INFO (coreCloseExecutor-96-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7284fc58
[junit4] 2> 49110 INFO (coreCloseExecutor-96-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=7284fc58
[junit4] 2> 49110 INFO (coreCloseExecutor-96-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6c98f2a2: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6a22a12e
[junit4] 2> 49127 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2e868f27{/bng/p,null,UNAVAILABLE}
[junit4] 2> 49128 INFO (TEST-TestDistributedGrouping.test-seed#[AA8A789492496763]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedGrouping -Dtests.method=test -Dtests.seed=AA8A789492496763 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=fi-FI -Dtests.timezone=Asia/Katmandu -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 3.56s J0 | TestDistributedGrouping.test <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: .grouped[a_i1].doclist.maxScore:1.0!=null
[junit4] > at __randomizedtesting.SeedInfo.seed([AA8A789492496763:22DE474E3CB50A9B]: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 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.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestDistributedGrouping_AA8A789492496763-003
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {other_tl1=PostingsFormat(name=LuceneVarGapFixedInterval), SubjectTerms_mfacet=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l_dv=BlockTreeOrds(blocksize=128), n_l1=PostingsFormat(name=LuceneVarGapFixedInterval), a_n_tdt=BlockTreeOrds(blocksize=128), multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), intDefault=PostingsFormat(name=LuceneVarGapFixedInterval), n_td1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), n_d1=PostingsFormat(name=LuceneVarGapFixedInterval), n_f1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l=PostingsFormat(name=LuceneVarGapFixedInterval), n_tl1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_s=PostingsFormat(name=LuceneFixedGap), n_tf1=PostingsFormat(name=LuceneVarGapFixedInterval), a_t=PostingsFormat(name=LuceneVarGapFixedInterval), id=BlockTreeOrds(blocksize=128), text=PostingsFormat(name=LuceneFixedGap), oddField_s1=PostingsFormat(name=LuceneVarGapFixedInterval), a_idv=PostingsFormat(name=LuceneFixedGap), timestamp=PostingsFormat(name=LuceneVarGapFixedInterval), n_dt1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_i1=PostingsFormat(name=LuceneVarGapFixedInterval), b_n_tdt=PostingsFormat(name=LuceneVarGapFixedInterval), n_ti1=BlockTreeOrds(blocksize=128), rnd_b=BlockTreeOrds(blocksize=128), foo_b=PostingsFormat(name=LuceneFixedGap), n_tdt1=PostingsFormat(name=LuceneVarGapFixedInterval), foo_d=BlockTreeOrds(blocksize=128), id_i1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_i_dv=PostingsFormat(name=LuceneVarGapFixedInterval), foo_f=PostingsFormat(name=LuceneFixedGap)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Direct), a_idv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=579, maxMBSortInHeap=6.896327708374894, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@13403f55), locale=fi-FI, timezone=Asia/Katmandu
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=3,threads=1,free=73749688,total=198180864
[junit4] 2> NOTE: All tests run in this JVM: [TestDistributedGrouping, TestDistributedGrouping, TestDistributedGrouping]
[junit4] Completed [5/5 (5!)] on J0 in 3.59s, 1 test, 1 failure <<< FAILURES!
[junit4]
[junit4]
[junit4] Tests with failures [seed: AA8A789492496763]:
[junit4] - org.apache.solr.TestDistributedGrouping.test
[junit4] - org.apache.solr.TestDistributedGrouping.test
[junit4] - org.apache.solr.TestDistributedGrouping.test
[junit4] - org.apache.solr.TestDistributedGrouping.test
[junit4] - org.apache.solr.TestDistributedGrouping.test
[junit4]
[junit4]
[junit4] JVM J0: 0.68 .. 49.97 = 49.29s
[junit4] JVM J1: 0.69 .. 46.53 = 45.85s
[junit4] Execution time total: 49 seconds
[junit4] Tests summary: 5 suites, 5 tests, 5 failures
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1568: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1092: There were test failures: 5 suites, 5 tests, 5 failures [seed: AA8A789492496763]
Total time: 52 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=/Users/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=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/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=8892c0d9aff9e8b7a6722a50a18908ef575276da, workspace=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX
[WARNINGS] Computing warning deltas based on reference build #4751
Recording test results
Setting ANT_1_8_2_HOME=/Users/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=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2