Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1783/
3 tests failed. FAILED: org.apache.solr.TestDistributedGrouping.test Error Message: Error from server at https://127.0.0.1:42473/_imo/collection1: Error from server at null: java.lang.NullPointerException at org.apache.solr.handler.component.ResponseBuilder.setResult(ResponseBuilder.java:466) at org.apache.solr.handler.component.QueryComponent.doProcessGroupedDistributedSearchSecondPhase(QueryComponent.java:1369) at org.apache.solr.handler.component.QueryComponent.process(QueryComponent.java:362) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:298) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2565) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:394) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:340) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:165) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) at org.eclipse.jetty.server.Server.handle(Server.java:502) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:411) at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:305) at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:159) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683) at java.lang.Thread.run(Thread.java:748) Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:42473/_imo/collection1: Error from server at null: java.lang.NullPointerException at org.apache.solr.handler.component.ResponseBuilder.setResult(ResponseBuilder.java:466) at org.apache.solr.handler.component.QueryComponent.doProcessGroupedDistributedSearchSecondPhase(QueryComponent.java:1369) at org.apache.solr.handler.component.QueryComponent.process(QueryComponent.java:362) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:298) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2565) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:394) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:340) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:165) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) at org.eclipse.jetty.server.Server.handle(Server.java:502) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:411) at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:305) at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:159) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([15E52028553B7669:9DB11FF2FBC71B91]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:649) 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.SolrRequest.process(SolrRequest.java:207) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:987) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1002) at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:626) at org.apache.solr.TestDistributedGrouping.simpleQuery(TestDistributedGrouping.java:321) at org.apache.solr.TestDistributedGrouping.test(TestDistributedGrouping.java:309) 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:1750) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1108) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054) 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:947) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) 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.cloud.autoscaling.HdfsAutoAddReplicasIntegrationTest.testSimple Error Message: Waiting for collection testSimple2 Timeout waiting to see state for collection=testSimple2 :DocCollection(testSimple2//collections/testSimple2/state.json/22)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node3/data/", "base_url":"https://127.0.0.1:36352/solr";, "node_name":"127.0.0.1:36352_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node3/data/tlog", "core":"testSimple2_shard1_replica_n1", "shared_storage":"true", "state":"down"}, "core_node5":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node5/data/", "base_url":"https://127.0.0.1:46093/solr";, "node_name":"127.0.0.1:46093_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node5/data/tlog", "core":"testSimple2_shard1_replica_n2", "shared_storage":"true", "state":"active", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"active", "replicas":{ "core_node7":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node7/data/", "base_url":"https://127.0.0.1:36352/solr";, "node_name":"127.0.0.1:36352_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node7/data/tlog", "core":"testSimple2_shard2_replica_n4", "shared_storage":"true", "state":"down"}, "core_node8":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node8/data/", "base_url":"https://127.0.0.1:46093/solr";, "node_name":"127.0.0.1:46093_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node8/data/tlog", "core":"testSimple2_shard2_replica_n6", "shared_storage":"true", "state":"active", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"true", "nrtReplicas":"2", "tlogReplicas":"0"} Live Nodes: [127.0.0.1:34550_solr, 127.0.0.1:46093_solr] Last available state: DocCollection(testSimple2//collections/testSimple2/state.json/22)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node3/data/", "base_url":"https://127.0.0.1:36352/solr";, "node_name":"127.0.0.1:36352_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node3/data/tlog", "core":"testSimple2_shard1_replica_n1", "shared_storage":"true", "state":"down"}, "core_node5":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node5/data/", "base_url":"https://127.0.0.1:46093/solr";, "node_name":"127.0.0.1:46093_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node5/data/tlog", "core":"testSimple2_shard1_replica_n2", "shared_storage":"true", "state":"active", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"active", "replicas":{ "core_node7":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node7/data/", "base_url":"https://127.0.0.1:36352/solr";, "node_name":"127.0.0.1:36352_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node7/data/tlog", "core":"testSimple2_shard2_replica_n4", "shared_storage":"true", "state":"down"}, "core_node8":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node8/data/", "base_url":"https://127.0.0.1:46093/solr";, "node_name":"127.0.0.1:46093_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node8/data/tlog", "core":"testSimple2_shard2_replica_n6", "shared_storage":"true", "state":"active", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"true", "nrtReplicas":"2", "tlogReplicas":"0"} Stack Trace: java.lang.AssertionError: Waiting for collection testSimple2 Timeout waiting to see state for collection=testSimple2 :DocCollection(testSimple2//collections/testSimple2/state.json/22)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node3/data/", "base_url":"https://127.0.0.1:36352/solr";, "node_name":"127.0.0.1:36352_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node3/data/tlog", "core":"testSimple2_shard1_replica_n1", "shared_storage":"true", "state":"down"}, "core_node5":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node5/data/", "base_url":"https://127.0.0.1:46093/solr";, "node_name":"127.0.0.1:46093_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node5/data/tlog", "core":"testSimple2_shard1_replica_n2", "shared_storage":"true", "state":"active", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"active", "replicas":{ "core_node7":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node7/data/", "base_url":"https://127.0.0.1:36352/solr";, "node_name":"127.0.0.1:36352_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node7/data/tlog", "core":"testSimple2_shard2_replica_n4", "shared_storage":"true", "state":"down"}, "core_node8":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node8/data/", "base_url":"https://127.0.0.1:46093/solr";, "node_name":"127.0.0.1:46093_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node8/data/tlog", "core":"testSimple2_shard2_replica_n6", "shared_storage":"true", "state":"active", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"true", "nrtReplicas":"2", "tlogReplicas":"0"} Live Nodes: [127.0.0.1:34550_solr, 127.0.0.1:46093_solr] Last available state: DocCollection(testSimple2//collections/testSimple2/state.json/22)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{ "shard1":{ "range":"80000000-ffffffff", "state":"active", "replicas":{ "core_node3":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node3/data/", "base_url":"https://127.0.0.1:36352/solr";, "node_name":"127.0.0.1:36352_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node3/data/tlog", "core":"testSimple2_shard1_replica_n1", "shared_storage":"true", "state":"down"}, "core_node5":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node5/data/", "base_url":"https://127.0.0.1:46093/solr";, "node_name":"127.0.0.1:46093_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node5/data/tlog", "core":"testSimple2_shard1_replica_n2", "shared_storage":"true", "state":"active", "leader":"true"}}}, "shard2":{ "range":"0-7fffffff", "state":"active", "replicas":{ "core_node7":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node7/data/", "base_url":"https://127.0.0.1:36352/solr";, "node_name":"127.0.0.1:36352_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node7/data/tlog", "core":"testSimple2_shard2_replica_n4", "shared_storage":"true", "state":"down"}, "core_node8":{ "dataDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node8/data/", "base_url":"https://127.0.0.1:46093/solr";, "node_name":"127.0.0.1:46093_solr", "type":"NRT", "force_set_state":"false", "ulogDir":"hdfs://localhost:41085/solr_hdfs_home/testSimple2/core_node8/data/tlog", "core":"testSimple2_shard2_replica_n6", "shared_storage":"true", "state":"active", "leader":"true"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"2", "autoAddReplicas":"true", "nrtReplicas":"2", "tlogReplicas":"0"} at __randomizedtesting.SeedInfo.seed([15E52028553B7669:2D5604D672C8A2B8]:0) at org.junit.Assert.fail(Assert.java:88) at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:310) at org.apache.solr.cloud.autoscaling.AutoAddReplicasIntegrationTest.testSimple(AutoAddReplicasIntegrationTest.java:169) 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:1750) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) 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:947) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) 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.handler.export.TestExportWriter.testIndexOrder Error Message: expected:<...ocs":[ {"id":"[1"}, {"id":"2"}, {"id":"3"}, {"id":"4]"}]}}> but was:<...ocs":[ {"id":"[3"}, {"id":"4"}, {"id":"1"}, {"id":"2]"}]}}> Stack Trace: org.junit.ComparisonFailure: expected:<...ocs":[ {"id":"[1"}, {"id":"2"}, {"id":"3"}, {"id":"4]"}]}}> but was:<...ocs":[ {"id":"[3"}, {"id":"4"}, {"id":"1"}, {"id":"2]"}]}}> at __randomizedtesting.SeedInfo.seed([15E52028553B7669:DCA38EAC7E924297]:0) at org.junit.Assert.assertEquals(Assert.java:115) at org.junit.Assert.assertEquals(Assert.java:144) at org.apache.solr.handler.export.TestExportWriter.assertJsonEquals(TestExportWriter.java:523) at org.apache.solr.handler.export.TestExportWriter.testIndexOrder(TestExportWriter.java:335) 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:1750) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) 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:947) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) 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 13465 lines...] [junit4] Suite: org.apache.solr.TestDistributedGrouping [junit4] 2> 543596 INFO (SUITE-TestDistributedGrouping-seed#[15E52028553B7669]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_15E52028553B7669-001/init-core-data-001 [junit4] 2> 543599 WARN (SUITE-TestDistributedGrouping-seed#[15E52028553B7669]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 543599 INFO (SUITE-TestDistributedGrouping-seed#[15E52028553B7669]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 543603 INFO (SUITE-TestDistributedGrouping-seed#[15E52028553B7669]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 543603 INFO (SUITE-TestDistributedGrouping-seed#[15E52028553B7669]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_imo/ [junit4] 2> 543880 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_15E52028553B7669-001/tempDir-001/control/cores/collection1 [junit4] 2> 543883 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 543883 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 543883 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 543883 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 543885 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 543885 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 543885 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 543887 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4364796a{/_imo,null,AVAILABLE} [junit4] 2> 543888 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5c24bb19[provider=null,keyStore=null,trustStore=null] [junit4] 2> 543888 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7709c4d8{SSL,[ssl, http/1.1]}{127.0.0.1:34711} [junit4] 2> 543888 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.Server Started @543949ms [junit4] 2> 543888 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_imo, hostPort=34711, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_15E52028553B7669-001/tempDir-001/control/cores} [junit4] 2> 543889 ERROR (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 543889 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 543889 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 543889 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 543889 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 543889 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-04T20:50:09.245Z [junit4] 2> 543889 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_15E52028553B7669-001/tempDir-001/control/solr.xml [junit4] 2> 543894 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 543894 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 543896 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2a7e34b8, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 543994 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 543995 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 543998 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@587763d[provider=null,keyStore=null,trustStore=null] [junit4] 2> 544008 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 544009 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7592957e[provider=null,keyStore=null,trustStore=null] [junit4] 2> 544051 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 544051 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 544085 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a7e34b8 [junit4] 2> 544100 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a7e34b8 [junit4] 2> 544100 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a7e34b8 [junit4] 2> 544103 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_15E52028553B7669-001/tempDir-001/control/cores [junit4] 2> 544103 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 544106 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 544168 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 544197 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 544343 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 544372 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 544372 INFO (coreLoadExecutor-2013-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@2a7e34b8 [junit4] 2> 544373 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_15E52028553B7669-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 544376 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1078672278, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 544462 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 544462 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 544465 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 544465 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 544466 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31870354055113853] [junit4] 2> 544467 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7098c077[collection1] main] [junit4] 2> 544468 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 544480 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 544531 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 544531 INFO (coreLoadExecutor-2013-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1627109573144870912 [junit4] 2> 544534 INFO (searcherExecutor-2014-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 544534 INFO (searcherExecutor-2014-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 544534 INFO (searcherExecutor-2014-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 544534 INFO (searcherExecutor-2014-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 544535 INFO (searcherExecutor-2014-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 544535 INFO (searcherExecutor-2014-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 544535 INFO (searcherExecutor-2014-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 544536 INFO (searcherExecutor-2014-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 544536 INFO (searcherExecutor-2014-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 544542 INFO (searcherExecutor-2014-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7098c077[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 544708 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_15E52028553B7669-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 544719 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 544719 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 544720 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 544720 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 544721 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 544721 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 544721 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 544721 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7404aee5{/_imo,null,AVAILABLE} [junit4] 2> 544722 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@372bcf67[provider=null,keyStore=null,trustStore=null] [junit4] 2> 544723 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4ba93315{SSL,[ssl, http/1.1]}{127.0.0.1:42473} [junit4] 2> 544723 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.s.Server Started @544784ms [junit4] 2> 544723 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_imo, hostPort=42473, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_15E52028553B7669-001/tempDir-001/shard0/cores} [junit4] 2> 544723 ERROR (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 544723 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 544723 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0 [junit4] 2> 544723 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 544723 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 544723 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-04T20:50:10.079Z [junit4] 2> 544724 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_15E52028553B7669-001/tempDir-001/shard0/solr.xml [junit4] 2> 544729 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 544729 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 544731 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2a7e34b8, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 544834 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false] [junit4] 2> 544835 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 544858 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@245deaf5[provider=null,keyStore=null,trustStore=null] [junit4] 2> 544863 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 544864 WARN (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6ab82ec1[provider=null,keyStore=null,trustStore=null] [junit4] 2> 544907 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 544907 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 544985 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a7e34b8 [junit4] 2> 545016 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a7e34b8 [junit4] 2> 545017 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a7e34b8 [junit4] 2> 545023 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_15E52028553B7669-001/tempDir-001/shard0/cores [junit4] 2> 545023 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 545024 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 545075 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 545106 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 545258 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 545269 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1, trusted=true [junit4] 2> 545270 INFO (coreLoadExecutor-2027-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@2a7e34b8 [junit4] 2> 545270 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_15E52028553B7669-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 545273 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1078672278, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 545351 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 545351 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 545354 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 545354 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 545356 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31870354055113853] [junit4] 2> 545356 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1222fc0d[collection1] main] [junit4] 2> 545357 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 545369 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 545438 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 545442 INFO (coreLoadExecutor-2027-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1627109574100123648 [junit4] 2> 545444 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 545445 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 545445 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 545445 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 545445 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 545447 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 545448 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 545449 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 545451 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 545453 INFO (searcherExecutor-2028-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1222fc0d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 545947 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 545994 INFO (qtp350246734-6769) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_imo path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1627109574672646144)} 0 6 [junit4] 2> 546018 INFO (qtp884358071-6793) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_imo path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1627109574699909120)} 0 4 [junit4] 2> 546025 INFO (qtp350246734-6770) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627109574711443456,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 546025 INFO (qtp350246734-6770) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 546026 INFO (qtp350246734-6770) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 546026 INFO (qtp350246734-6770) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_imo path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1 [junit4] 2> 546033 INFO (qtp884358071-6794) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1627109574719832064,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 546033 INFO (qtp884358071-6794) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 546034 INFO (qtp884358071-6794) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 546034 INFO (qtp884358071-6794) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/_imo path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1 [junit4] 2> 546057 INFO (qtp350246734-6771) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=21 [junit4] 2> 546302 INFO (qtp884358071-6794) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611417&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=24 [junit4] 2> 546329 INFO (qtp884358071-6790) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=[ff01::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&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=268 [junit4] 2> 546329 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.BaseDistributedSearchTestCase starting stress... [junit4] 2> 546346 INFO (qtp884358071-6792) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611698&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546351 INFO (qtp884358071-6794) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=[ff01::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&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=8 [junit4] 2> 546355 INFO (qtp884358071-6791) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611702&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546363 INFO (qtp884358071-6790) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=[ff01::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&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=17 [junit4] 2> 546380 INFO (qtp884358071-6837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611734&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546387 INFO (qtp884358071-6790) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611708&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=2 [junit4] 2> 546421 INFO (qtp884358071-6793) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=[ff01::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&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=69 [junit4] 2> 546421 INFO (qtp884358071-6838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=[ff01::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&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=43 [junit4] 2> 546424 INFO (qtp884358071-6794) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611704&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546431 INFO (qtp884358071-6792) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=[ff01::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&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=83 [junit4] 2> 546435 INFO (qtp884358071-6791) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611789&isShard=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546436 INFO (qtp884358071-6839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=[ff01::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1|[ff01::213]:33332/_imo&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> 546442 INFO (qtp350246734-6767) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=4 [junit4] 2> 546446 INFO (qtp884358071-6790) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611800&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546448 INFO (qtp884358071-6837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&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=3 [junit4] 2> 546448 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.BaseDistributedSearchTestCase starting stress... [junit4] 2> 546454 INFO (qtp884358071-6792) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611807&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546455 INFO (qtp884358071-6791) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611809&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546455 INFO (qtp884358071-6793) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&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=4 [junit4] 2> 546456 INFO (qtp884358071-6794) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&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> 546457 INFO (qtp884358071-6790) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611812&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546458 INFO (qtp884358071-6794) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&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> 546464 INFO (qtp884358071-6837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611809&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546465 INFO (qtp884358071-6838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&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=12 [junit4] 2> 546467 INFO (qtp884358071-6792) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611821&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546468 INFO (qtp884358071-6838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&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> 546469 INFO (qtp884358071-6839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611824&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546470 INFO (qtp884358071-6793) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::114]:33332/_imo&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> 546486 INFO (qtp350246734-6768) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=14 [junit4] 2> 546490 INFO (qtp884358071-6794) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611845&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546509 INFO (qtp884358071-6837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1551732611845&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=7 [junit4] 2> 546517 INFO (qtp884358071-6790) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&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=28 [junit4] 2> 546518 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.BaseDistributedSearchTestCase starting stress... [junit4] 2> 546522 INFO (qtp884358071-6838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611876&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546522 INFO (qtp884358071-6839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611876&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546523 INFO (qtp884358071-6794) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611878&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546524 INFO (qtp884358071-6837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1551732611876&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546524 INFO (qtp884358071-6794) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1551732611876&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546525 INFO (qtp884358071-6792) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&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=5 [junit4] 2> 546525 INFO (qtp884358071-6791) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&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=5 [junit4] 2> 546527 INFO (qtp884358071-6839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1551732611878&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546528 INFO (qtp884358071-6838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&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=5 [junit4] 2> 546529 INFO (qtp884358071-6790) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611884&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546531 INFO (qtp884358071-6794) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611885&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546534 INFO (qtp884358071-6792) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1551732611884&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546534 INFO (qtp884358071-6791) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1551732611885&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546535 INFO (qtp884358071-6793) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&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=6 [junit4] 2> 546535 INFO (qtp884358071-6837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&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=6 [junit4] 2> 546538 INFO (qtp884358071-6838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&q=*:*&NOW=1551732611892&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546540 INFO (qtp884358071-6790) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1551732611892&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true} status=0 QTime=0 [junit4] 2> 546541 INFO (qtp884358071-6839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=https://127.0.0.1:42473/_imo/collection1|[ff01::083]:33332/_imo&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=4 [junit4] 2> 546562 INFO (qtp350246734-6769) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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=19 [junit4] 2> 546568 INFO (qtp884358071-6792) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1551732611921&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0 [junit4] 2> 546571 INFO (qtp884358071-6791) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1551732611921&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=1 [junit4] 2> 546573 INFO (qtp884358071-6794) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=[ff01::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/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=7 [junit4] 2> 546574 INFO (TEST-TestDistributedGrouping.test-seed#[15E52028553B7669]) [ ] o.a.s.BaseDistributedSearchTestCase starting stress... [junit4] 2> 546577 INFO (qtp884358071-6838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1551732611931&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0 [junit4] 2> 546578 INFO (qtp884358071-6839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1551732611932&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0 [junit4] 2> 546578 INFO (qtp884358071-6839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1551732611931&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0 [junit4] 2> 546581 INFO (qtp884358071-6791) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1551732611931&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0 [junit4] 2> 546582 INFO (qtp884358071-6794) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1551732611932&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0 [junit4] 2> 546583 INFO (qtp884358071-6838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo 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::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1551732611931&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1} status=0 QTime=0 [junit4] 2> 546583 INFO (qtp884358071-6790) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=[ff01::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/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=6 [junit4] 2> 546583 INFO (qtp884358071-6837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/_imo path=/select params={q=*:*&shards=[ff01::083]:33332/_imo|[ff01::114]:33332/_imo|https://127.0.0.1:42473/_imo/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=8 [junit4] 2> 546584 INFO (qtp884358071-6793) [ x:collecti [...truncated too long message...] property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: ivy-availability-check: [loadresource] Do not set property disallowed.ivy.jars.list as its length is 0. -ivy-fail-disallowed-ivy-version: ivy-fail: ivy-fail: ivy-configure: [ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml resolve: jar-checksums: [mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1904382940 [copy] Copying 239 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1904382940 [delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1904382940 check-working-copy: [ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working [ivy:cachepath] confs: [default] [ivy:cachepath] found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public [ivy:cachepath] found com.jcraft#jsch;0.1.53 in public [ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpclient;4.3.6 in public [ivy:cachepath] found org.apache.httpcomponents#httpcore;4.3.3 in public [ivy:cachepath] found commons-logging#commons-logging;1.1.3 in public [ivy:cachepath] found commons-codec#commons-codec;1.6 in public [ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public [ivy:cachepath] :: resolution report :: resolve 51ms :: artifacts dl 4ms --------------------------------------------------------------------- | | modules || artifacts | | conf | number| search|dwnlded|evicted|| number|dwnlded| --------------------------------------------------------------------- | default | 8 | 0 | 0 | 0 || 8 | 0 | --------------------------------------------------------------------- [wc-checker] Initializing working copy... [wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". [wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation [wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. [wc-checker] Checking working copy status... -jenkins-base: BUILD SUCCESSFUL Total time: 378 minutes 57 seconds Archiving artifacts java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726) at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707) at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086) Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741) at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357) at hudson.remoting.Channel.call(Channel.java:955) at hudson.FilePath.act(FilePath.java:1072) at hudson.FilePath.act(FilePath.java:1061) at hudson.FilePath.validateAntFileMask(FilePath.java:2705) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1835) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) Caused: hudson.FilePath$TunneledInterruptedException at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3088) at hudson.remoting.UserRequest.perform(UserRequest.java:212) at hudson.remoting.UserRequest.perform(UserRequest.java:54) at hudson.remoting.Request$2.run(Request.java:369) at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:748) Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000 at hudson.FilePath.act(FilePath.java:1074) at hudson.FilePath.act(FilePath.java:1061) at hudson.FilePath.validateAntFileMask(FilePath.java:2705) at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) at hudson.model.Build$BuildExecution.post2(Build.java:186) at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) at hudson.model.Run.execute(Run.java:1835) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:429) No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error? Recording test results 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)
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
