You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/07/13 17:41:34 UTC
[JENKINS] Lucene-Solr-Tests-7.x - Build # 44 - Still Failing
Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/44/
2 tests failed.
FAILED: org.apache.solr.cloud.TestPullReplicaErrorHandling.testCantConnectToPullReplica
Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:39926/solr/pull_replica_error_handling_test_cant_connect_to_pull_replica_shard1_replica_n1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:39926/solr/pull_replica_error_handling_test_cant_connect_to_pull_replica_shard1_replica_n1
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:637)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:252)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957)
at org.apache.solr.cloud.TestPullReplicaErrorHandling.assertNumDocs(TestPullReplicaErrorHandling.java:240)
at org.apache.solr.cloud.TestPullReplicaErrorHandling.assertNumDocs(TestPullReplicaErrorHandling.java:250)
at org.apache.solr.cloud.TestPullReplicaErrorHandling.testCantConnectToPullReplica(TestPullReplicaErrorHandling.java:150)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:139)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:155)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:284)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:165)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:167)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:523)
... 47 more
FAILED: org.apache.solr.cloud.TestPullReplicaErrorHandling.testPullReplicaDisconnectsFromZooKeeper
Error Message:
IOException occured when talking to server at: http://127.0.0.1:33523/solr
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:33523/solr
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:640)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:252)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1121)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:195)
at org.apache.solr.cloud.TestPullReplicaErrorHandling.testPullReplicaDisconnectsFromZooKeeper(TestPullReplicaErrorHandling.java:216)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:33523 failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:165)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:167)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:523)
... 49 more
Build Log:
[...truncated 12605 lines...]
[junit4] Suite: org.apache.solr.cloud.TestPullReplicaErrorHandling
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/init-core-data-001
[junit4] 2> 1725904 WARN (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1725905 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1725906 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 1725906 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001
[junit4] 2> 1725906 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1725906 INFO (Thread-2510) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1725906 INFO (Thread-2510) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1725933 ERROR (Thread-2510) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1726006 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:39074
[junit4] 2> 1726010 INFO (jetty-launcher-1608-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1726011 INFO (jetty-launcher-1608-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1726011 INFO (jetty-launcher-1608-thread-3) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1726011 INFO (jetty-launcher-1608-thread-4) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1726011 INFO (jetty-launcher-1608-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1ee7847{/solr,null,AVAILABLE}
[junit4] 2> 1726011 INFO (jetty-launcher-1608-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@262dae29{HTTP/1.1,[http/1.1]}{127.0.0.1:๔๕๒๑๕}
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-1) [ ] o.e.j.s.Server Started @๑๗๓๕๓๑๐ms
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45215}
[junit4] 2> 1726012 ERROR (jetty-launcher-1608-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-13T16:41:35.927Z
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@788239cf{/solr,null,AVAILABLE}
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7e7630e4{HTTP/1.1,[http/1.1]}{127.0.0.1:๓๙๙๐๑}
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-2) [ ] o.e.j.s.Server Started @๑๗๓๕๓๑๑ms
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@52bfa7e3{/solr,null,AVAILABLE}
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39901}
[junit4] 2> 1726012 ERROR (jetty-launcher-1608-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1726012 INFO (jetty-launcher-1608-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0
[junit4] 2> 1726013 INFO (jetty-launcher-1608-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1726013 INFO (jetty-launcher-1608-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1726013 INFO (jetty-launcher-1608-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@54be213a{/solr,null,AVAILABLE}
[junit4] 2> 1726013 INFO (jetty-launcher-1608-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-13T16:41:35.928Z
[junit4] 2> 1726013 INFO (jetty-launcher-1608-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2dfec00e{HTTP/1.1,[http/1.1]}{127.0.0.1:๓๖๔๘๗}
[junit4] 2> 1726013 INFO (jetty-launcher-1608-thread-4) [ ] o.e.j.s.Server Started @๑๗๓๕๓๑๑ms
[junit4] 2> 1726013 INFO (jetty-launcher-1608-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36487}
[junit4] 2> 1726013 ERROR (jetty-launcher-1608-thread-4) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1726013 INFO (jetty-launcher-1608-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0
[junit4] 2> 1726013 INFO (jetty-launcher-1608-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1726013 INFO (jetty-launcher-1608-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1726013 INFO (jetty-launcher-1608-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-13T16:41:35.928Z
[junit4] 2> 1726030 INFO (jetty-launcher-1608-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4ff68573{HTTP/1.1,[http/1.1]}{127.0.0.1:๔๖๒๕๕}
[junit4] 2> 1726031 INFO (jetty-launcher-1608-thread-3) [ ] o.e.j.s.Server Started @๑๗๓๕๓๒๙ms
[junit4] 2> 1726031 INFO (jetty-launcher-1608-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46255}
[junit4] 2> 1726032 ERROR (jetty-launcher-1608-thread-3) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1726032 INFO (jetty-launcher-1608-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0
[junit4] 2> 1726032 INFO (jetty-launcher-1608-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1726032 INFO (jetty-launcher-1608-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1726032 INFO (jetty-launcher-1608-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-13T16:41:35.947Z
[junit4] 2> 1726033 INFO (jetty-launcher-1608-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1726033 INFO (jetty-launcher-1608-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1726034 INFO (jetty-launcher-1608-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1726071 INFO (jetty-launcher-1608-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1726076 INFO (jetty-launcher-1608-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39074/solr
[junit4] 2> 1726108 INFO (jetty-launcher-1608-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39074/solr
[junit4] 2> 1726109 INFO (jetty-launcher-1608-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39074/solr
[junit4] 2> 1726110 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15d3cd3bd680005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1726111 INFO (jetty-launcher-1608-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39074/solr
[junit4] 2> 1726408 INFO (jetty-launcher-1608-thread-3) [n:127.0.0.1:46255_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1726409 INFO (jetty-launcher-1608-thread-3) [n:127.0.0.1:46255_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46255_solr
[junit4] 2> 1726409 INFO (jetty-launcher-1608-thread-3) [n:127.0.0.1:46255_solr ] o.a.s.c.Overseer Overseer (id=98301646985494540-127.0.0.1:46255_solr-n_0000000000) starting
[junit4] 2> 1726410 INFO (jetty-launcher-1608-thread-4) [n:127.0.0.1:36487_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1726411 INFO (jetty-launcher-1608-thread-2) [n:127.0.0.1:39901_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1726411 INFO (jetty-launcher-1608-thread-4) [n:127.0.0.1:36487_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36487_solr
[junit4] 2> 1726411 INFO (jetty-launcher-1608-thread-2) [n:127.0.0.1:39901_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39901_solr
[junit4] 2> 1726424 INFO (zkCallback-1631-thread-1-processing-n:127.0.0.1:46255_solr) [n:127.0.0.1:46255_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1726424 INFO (zkCallback-1629-thread-1-processing-n:127.0.0.1:36487_solr) [n:127.0.0.1:36487_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1726441 INFO (zkCallback-1630-thread-1-processing-n:127.0.0.1:39901_solr) [n:127.0.0.1:39901_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1726441 INFO (zkCallback-1625-thread-1-processing-n:127.0.0.1:45215_solr) [n:127.0.0.1:45215_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1726442 INFO (jetty-launcher-1608-thread-1) [n:127.0.0.1:45215_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1726443 INFO (jetty-launcher-1608-thread-3) [n:127.0.0.1:46255_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46255_solr
[junit4] 2> 1726443 INFO (jetty-launcher-1608-thread-1) [n:127.0.0.1:45215_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45215_solr
[junit4] 2> 1726444 INFO (zkCallback-1630-thread-1-processing-n:127.0.0.1:39901_solr) [n:127.0.0.1:39901_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1726444 INFO (zkCallback-1629-thread-1-processing-n:127.0.0.1:36487_solr) [n:127.0.0.1:36487_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1726444 INFO (zkCallback-1625-thread-1-processing-n:127.0.0.1:45215_solr) [n:127.0.0.1:45215_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1726465 INFO (zkCallback-1629-thread-1-processing-n:127.0.0.1:36487_solr) [n:127.0.0.1:36487_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1726465 INFO (zkCallback-1630-thread-1-processing-n:127.0.0.1:39901_solr) [n:127.0.0.1:39901_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1726465 INFO (zkCallback-1631-thread-1-processing-n:127.0.0.1:46255_solr) [n:127.0.0.1:46255_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 1726465 INFO (zkCallback-1625-thread-1-processing-n:127.0.0.1:45215_solr) [n:127.0.0.1:45215_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1726654 INFO (jetty-launcher-1608-thread-1) [n:127.0.0.1:45215_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45215.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726677 INFO (jetty-launcher-1608-thread-1) [n:127.0.0.1:45215_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45215.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726677 INFO (jetty-launcher-1608-thread-1) [n:127.0.0.1:45215_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45215.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726678 INFO (jetty-launcher-1608-thread-1) [n:127.0.0.1:45215_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node1/.
[junit4] 2> 1726739 INFO (jetty-launcher-1608-thread-2) [n:127.0.0.1:39901_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39901.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726742 INFO (jetty-launcher-1608-thread-2) [n:127.0.0.1:39901_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39901.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726742 INFO (jetty-launcher-1608-thread-2) [n:127.0.0.1:39901_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39901.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726742 INFO (jetty-launcher-1608-thread-2) [n:127.0.0.1:39901_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node2/.
[junit4] 2> 1726800 INFO (jetty-launcher-1608-thread-4) [n:127.0.0.1:36487_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36487.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726803 INFO (jetty-launcher-1608-thread-4) [n:127.0.0.1:36487_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36487.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726803 INFO (jetty-launcher-1608-thread-4) [n:127.0.0.1:36487_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36487.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726804 INFO (jetty-launcher-1608-thread-4) [n:127.0.0.1:36487_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node4/.
[junit4] 2> 1726856 INFO (jetty-launcher-1608-thread-3) [n:127.0.0.1:46255_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46255.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726859 INFO (jetty-launcher-1608-thread-3) [n:127.0.0.1:46255_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46255.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726859 INFO (jetty-launcher-1608-thread-3) [n:127.0.0.1:46255_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46255.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1726860 INFO (jetty-launcher-1608-thread-3) [n:127.0.0.1:46255_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node3/.
[junit4] 2> 1726925 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1726925 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39074/solr ready
[junit4] 2> 1726929 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@262dae29{HTTP/1.1,[http/1.1]}{127.0.0.1:๐}
[junit4] 2> 1726930 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2111478824
[junit4] 2> 1726930 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1726931 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1726933 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1726934 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1726934 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1726935 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15d3cd3bd680007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1726935 INFO (zkCallback-1630-thread-1-processing-n:127.0.0.1:39901_solr) [n:127.0.0.1:39901_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1726935 INFO (zkCallback-1631-thread-1-processing-n:127.0.0.1:46255_solr) [n:127.0.0.1:46255_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1726936 INFO (zkCallback-1638-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1726943 INFO (zkCallback-1629-thread-1-processing-n:127.0.0.1:36487_solr) [n:127.0.0.1:36487_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1728435 WARN (zkCallback-1625-thread-1-processing-n:127.0.0.1:45215_solr) [n:127.0.0.1:45215_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1728435 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1ee7847{/solr,null,UNAVAILABLE}
[junit4] 2> 1728436 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1728437 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@32eb4b2{/solr,null,AVAILABLE}
[junit4] 2> 1728437 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@40b9f3cd{HTTP/1.1,[http/1.1]}{127.0.0.1:๔๑๓๗๖}
[junit4] 2> 1728438 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.Server Started @๑๗๓๗๗๓๖ms
[junit4] 2> 1728438 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36986}
[junit4] 2> 1728438 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1728438 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0
[junit4] 2> 1728438 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1728438 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1728438 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-13T16:41:38.353Z
[junit4] 2> 1728440 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1728462 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39074/solr
[junit4] 2> 1728467 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1728468 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:36986_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1728469 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:36986_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36986_solr
[junit4] 2> 1728470 INFO (zkCallback-1638-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1728470 INFO (zkCallback-1629-thread-1-processing-n:127.0.0.1:36487_solr) [n:127.0.0.1:36487_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1728470 INFO (zkCallback-1630-thread-1-processing-n:127.0.0.1:39901_solr) [n:127.0.0.1:39901_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1728470 INFO (zkCallback-1631-thread-2-processing-n:127.0.0.1:46255_solr) [n:127.0.0.1:46255_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1728470 INFO (zkCallback-1643-thread-1-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1728555 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:36986_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36986.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1728558 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:36986_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36986.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1728558 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:36986_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36986.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1728559 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:36986_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node1/.
[junit4] 2> 1728590 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.TestPullReplicaErrorHandling Adding proxy for URL: http://127.0.0.1:41376/solr. Proxy: http://127.0.0.1:36986/solr
[junit4] 2> 1728591 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7e7630e4{HTTP/1.1,[http/1.1]}{127.0.0.1:๐}
[junit4] 2> 1728592 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=458177786
[junit4] 2> 1728592 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1728593 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1728594 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1728608 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1728609 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1728609 INFO (zkCallback-1629-thread-1-processing-n:127.0.0.1:36487_solr) [n:127.0.0.1:36487_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1728609 INFO (zkCallback-1631-thread-2-processing-n:127.0.0.1:46255_solr) [n:127.0.0.1:46255_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1728610 INFO (zkCallback-1638-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1728610 INFO (zkCallback-1643-thread-2-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1730109 WARN (zkCallback-1630-thread-1-processing-n:127.0.0.1:39901_solr) [n:127.0.0.1:39901_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1730110 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@788239cf{/solr,null,UNAVAILABLE}
[junit4] 2> 1730110 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1730111 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1283dd59{/solr,null,AVAILABLE}
[junit4] 2> 1730111 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@68a46434{HTTP/1.1,[http/1.1]}{127.0.0.1:๓๕๗๕๐}
[junit4] 2> 1730112 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.Server Started @๑๗๓๙๔๑๐ms
[junit4] 2> 1730112 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33523}
[junit4] 2> 1730112 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1730112 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0
[junit4] 2> 1730112 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1730112 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1730112 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-13T16:41:40.027Z
[junit4] 2> 1730114 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1730132 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39074/solr
[junit4] 2> 1730138 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:33523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1730139 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:33523_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1730140 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:33523_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33523_solr
[junit4] 2> 1730141 INFO (zkCallback-1638-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1730141 INFO (zkCallback-1643-thread-2-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1730141 INFO (zkCallback-1629-thread-1-processing-n:127.0.0.1:36487_solr) [n:127.0.0.1:36487_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1730141 INFO (zkCallback-1631-thread-2-processing-n:127.0.0.1:46255_solr) [n:127.0.0.1:46255_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1730141 INFO (zkCallback-1649-thread-1-processing-n:127.0.0.1:33523_solr) [n:127.0.0.1:33523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1730269 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:33523_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33523.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1730272 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:33523_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33523.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1730273 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:33523_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33523.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1730273 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:33523_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node2/.
[junit4] 2> 1730304 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.TestPullReplicaErrorHandling Adding proxy for URL: http://127.0.0.1:35750/solr. Proxy: http://127.0.0.1:33523/solr
[junit4] 2> 1730305 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2dfec00e{HTTP/1.1,[http/1.1]}{127.0.0.1:๐}
[junit4] 2> 1730306 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1995512352
[junit4] 2> 1730306 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1730308 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1730309 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1730310 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1730310 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1730311 INFO (zkCallback-1638-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1730311 INFO (zkCallback-1643-thread-2-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1730311 INFO (zkCallback-1631-thread-2-processing-n:127.0.0.1:46255_solr) [n:127.0.0.1:46255_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1730311 INFO (zkCallback-1649-thread-1-processing-n:127.0.0.1:33523_solr) [n:127.0.0.1:33523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1731811 WARN (zkCallback-1629-thread-1-processing-n:127.0.0.1:36487_solr) [n:127.0.0.1:36487_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1731811 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@54be213a{/solr,null,UNAVAILABLE}
[junit4] 2> 1731812 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1731813 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2f0ad4f5{/solr,null,AVAILABLE}
[junit4] 2> 1731813 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@33106368{HTTP/1.1,[http/1.1]}{127.0.0.1:๔๔๖๐๕}
[junit4] 2> 1731813 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.Server Started @๑๗๔๑๑๑๒ms
[junit4] 2> 1731813 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39926}
[junit4] 2> 1731814 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1731814 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0
[junit4] 2> 1731814 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1731814 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1731814 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-13T16:41:41.729Z
[junit4] 2> 1731815 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1731830 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39074/solr
[junit4] 2> 1731835 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:39926_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1731836 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:39926_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1731838 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:39926_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39926_solr
[junit4] 2> 1731838 INFO (zkCallback-1638-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1731838 INFO (zkCallback-1649-thread-1-processing-n:127.0.0.1:33523_solr) [n:127.0.0.1:33523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1731838 INFO (zkCallback-1631-thread-2-processing-n:127.0.0.1:46255_solr) [n:127.0.0.1:46255_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1731838 INFO (zkCallback-1643-thread-1-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1731839 INFO (zkCallback-1655-thread-1-processing-n:127.0.0.1:39926_solr) [n:127.0.0.1:39926_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1731919 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:39926_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39926.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1731922 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:39926_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39926.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1731922 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:39926_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39926.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1731923 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:39926_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node4/.
[junit4] 2> 1731945 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.TestPullReplicaErrorHandling Adding proxy for URL: http://127.0.0.1:44605/solr. Proxy: http://127.0.0.1:39926/solr
[junit4] 2> 1731946 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4ff68573{HTTP/1.1,[http/1.1]}{127.0.0.1:๐}
[junit4] 2> 1731946 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1872266214
[junit4] 2> 1731946 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1731947 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1731949 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1731950 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1731950 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1731950 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.Overseer Overseer (id=98301646985494540-127.0.0.1:46255_solr-n_0000000000) closing
[junit4] 2> 1731950 INFO (OverseerStateUpdate-98301646985494540-127.0.0.1:46255_solr-n_0000000000) [n:127.0.0.1:46255_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:46255_solr
[junit4] 2> 1731951 INFO (zkCallback-1638-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1731951 INFO (zkCallback-1649-thread-1-processing-n:127.0.0.1:33523_solr) [n:127.0.0.1:33523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1731951 INFO (zkCallback-1643-thread-2-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1731951 INFO (zkCallback-1643-thread-1-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36986_solr
[junit4] 2> 1731951 INFO (zkCallback-1655-thread-1-processing-n:127.0.0.1:39926_solr) [n:127.0.0.1:39926_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1731952 INFO (zkCallback-1643-thread-1-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.Overseer Overseer (id=98301646985494545-127.0.0.1:36986_solr-n_0000000004) starting
[junit4] 2> 1733451 WARN (zkCallback-1631-thread-2-processing-n:127.0.0.1:46255_solr) [n:127.0.0.1:46255_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1733452 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@52bfa7e3{/solr,null,UNAVAILABLE}
[junit4] 2> 1733452 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1733453 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@43c18a0c{/solr,null,AVAILABLE}
[junit4] 2> 1733453 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1db67c79{HTTP/1.1,[http/1.1]}{127.0.0.1:๔๔๐๘๓}
[junit4] 2> 1733454 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.e.j.s.Server Started @๑๗๔๒๗๕๒ms
[junit4] 2> 1733454 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35134}
[junit4] 2> 1733454 ERROR (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1733454 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0
[junit4] 2> 1733454 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1733454 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 1733454 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-13T16:41:43.369Z
[junit4] 2> 1733465 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1733497 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39074/solr
[junit4] 2> 1733503 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:35134_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1733504 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:35134_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1733505 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:35134_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35134_solr
[junit4] 2> 1733506 INFO (zkCallback-1638-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1733506 INFO (zkCallback-1649-thread-1-processing-n:127.0.0.1:33523_solr) [n:127.0.0.1:33523_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1733506 INFO (zkCallback-1643-thread-1-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1733506 INFO (zkCallback-1655-thread-1-processing-n:127.0.0.1:39926_solr) [n:127.0.0.1:39926_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1733506 INFO (zkCallback-1661-thread-1-processing-n:127.0.0.1:35134_solr) [n:127.0.0.1:35134_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1733646 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:35134_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35134.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1733649 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:35134_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35134.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1733649 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:35134_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35134.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1733650 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [n:127.0.0.1:35134_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node3/.
[junit4] 2> 1733680 INFO (SUITE-TestPullReplicaErrorHandling-seed#[553103EC36ACA9E6]-worker) [ ] o.a.s.c.TestPullReplicaErrorHandling Adding proxy for URL: http://127.0.0.1:44083/solr. Proxy: http://127.0.0.1:35134/solr
[junit4] 2> 1733682 INFO (SocketProxy-Acceptor-33523) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=60326,localport=33523], receiveBufferSize:531000
[junit4] 2> 1733684 INFO (SocketProxy-Acceptor-33523) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=35750,localport=55832], receiveBufferSize=530904
[junit4] 2> 1733697 INFO (qtp1208169753-9943) [n:127.0.0.1:33523_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with params val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1733698 INFO (qtp1208169753-9943) [n:127.0.0.1:33523_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1733748 INFO (TEST-TestPullReplicaErrorHandling.testCantConnectToLeader-seed#[553103EC36ACA9E6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCantConnectToLeader
[junit4] 2> 1733749 INFO (qtp1208169753-9944) [n:127.0.0.1:33523_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=1&replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=pull_replica_error_handling_test_cant_connect_to_leader&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1733751 INFO (OverseerThreadFactory-3794-thread-1-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.CreateCollectionCmd Create collection pull_replica_error_handling_test_cant_connect_to_leader
[junit4] 2> 1733854 INFO (OverseerStateUpdate-98301646985494545-127.0.0.1:36986_solr-n_0000000004) [n:127.0.0.1:36986_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"pull_replica_error_handling_test_cant_connect_to_leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:35134/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 1733855 INFO (OverseerStateUpdate-98301646985494545-127.0.0.1:36986_solr-n_0000000004) [n:127.0.0.1:36986_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"pull_replica_error_handling_test_cant_connect_to_leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:36986/solr",
[junit4] 2> "type":"PULL"}
[junit4] 2> 1734056 INFO (SocketProxy-Acceptor-35134) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=46378,localport=35134], receiveBufferSize:531000
[junit4] 2> 1734057 INFO (SocketProxy-Acceptor-36986) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=46784,localport=36986], receiveBufferSize:531000
[junit4] 2> 1734057 INFO (SocketProxy-Acceptor-35134) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=44083,localport=51134], receiveBufferSize=530904
[junit4] 2> 1734058 INFO (SocketProxy-Acceptor-36986) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=41376,localport=56808], receiveBufferSize=530904
[junit4] 2> 1734060 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node1&collection.configName=conf&newCollection=true&name=pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1&action=CREATE&numShards=1&collection=pull_replica_error_handling_test_cant_connect_to_leader&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1734060 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1734066 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1&action=CREATE&numShards=1&collection=pull_replica_error_handling_test_cant_connect_to_leader&shard=shard1&wt=javabin&version=2&replicaType=PULL
[junit4] 2> 1734085 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1734188 INFO (zkCallback-1661-thread-1-processing-n:127.0.0.1:35134_solr) [n:127.0.0.1:35134_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json] for collection [pull_replica_error_handling_test_cant_connect_to_leader] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1734188 INFO (zkCallback-1643-thread-2-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json] for collection [pull_replica_error_handling_test_cant_connect_to_leader] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1734188 INFO (zkCallback-1643-thread-1-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json] for collection [pull_replica_error_handling_test_cant_connect_to_leader] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1734188 INFO (zkCallback-1661-thread-2-processing-n:127.0.0.1:35134_solr) [n:127.0.0.1:35134_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json] for collection [pull_replica_error_handling_test_cant_connect_to_leader] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1735069 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1735104 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.s.IndexSchema [pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1735106 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1735106 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1' using configuration from collection pull_replica_error_handling_test_cant_connect_to_leader, trusted=true
[junit4] 2> 1735107 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35134.solr.core.pull_replica_error_handling_test_cant_connect_to_leader.shard1.replica_n1' (registry 'solr.core.pull_replica_error_handling_test_cant_connect_to_leader.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1735107 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1735107 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1735107 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.SolrCore [[pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node3/pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node3/./pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1/data/]
[junit4] 2> 1735112 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.s.IndexSchema [pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] Schema name=minimal
[junit4] 2> 1735131 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1735131 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.c.CoreContainer Creating SolrCore 'pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1' using configuration from collection pull_replica_error_handling_test_cant_connect_to_leader, trusted=true
[junit4] 2> 1735131 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_36986.solr.core.pull_replica_error_handling_test_cant_connect_to_leader.shard1.replica_p1' (registry 'solr.core.pull_replica_error_handling_test_cant_connect_to_leader.shard1.replica_p1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3bae4557
[junit4] 2> 1735131 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1735131 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.c.SolrCore [[pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node1/pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestPullReplicaErrorHandling_553103EC36ACA9E6-001/tempDir-001/node1/./pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1/data/]
[junit4] 2> 1735213 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1735213 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1735214 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1735214 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1735215 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@23d981cf[pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] main]
[junit4] 2> 1735216 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1735216 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1735217 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1735218 INFO (searcherExecutor-3801-thread-1-processing-n:127.0.0.1:35134_solr x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1 s:shard1 c:pull_replica_error_handling_test_cant_connect_to_leader r:core_node1) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.SolrCore [pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] Registered new searcher Searcher@23d981cf[pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1735218 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1735218 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1735218 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1572826361503940608
[junit4] 2> 1735219 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e2d4c1f[pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] main]
[junit4] 2> 1735228 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1735228 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1735229 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1735230 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.c.ZkController pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1 starting background replication from leader
[junit4] 2> 1735230 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03
[junit4] 2> 1735230 INFO (searcherExecutor-3802-thread-1-processing-n:127.0.0.1:36986_solr x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1 s:shard1 c:pull_replica_error_handling_test_cant_connect_to_leader r:core_node2) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.c.SolrCore [pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] Registered new searcher Searcher@e2d4c1f[pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1735231 INFO (qtp1295318019-9918) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms
[junit4] 2> 1735241 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1735241 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1735241 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:35134/solr/pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1/
[junit4] 2> 1735241 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1735241 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:35134/solr/pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1/ has no replicas
[junit4] 2> 1735241 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 1735244 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:35134/solr/pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1/ shard1
[junit4] 2> 1735345 INFO (zkCallback-1661-thread-1-processing-n:127.0.0.1:35134_solr) [n:127.0.0.1:35134_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json] for collection [pull_replica_error_handling_test_cant_connect_to_leader] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1735345 INFO (zkCallback-1643-thread-1-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json] for collection [pull_replica_error_handling_test_cant_connect_to_leader] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1735346 INFO (zkCallback-1661-thread-2-processing-n:127.0.0.1:35134_solr) [n:127.0.0.1:35134_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json] for collection [pull_replica_error_handling_test_cant_connect_to_leader] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1735346 INFO (zkCallback-1643-thread-2-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json] for collection [pull_replica_error_handling_test_cant_connect_to_leader] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1735381 INFO (indexFetcher-3811-thread-1) [n:127.0.0.1:36986_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node2 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_p1] o.a.s.h.IndexFetcher Replica core_node1 is leader but it's state is down, skipping replication
[junit4] 2> 1735395 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1735396 INFO (qtp1303997840-9995) [n:127.0.0.1:35134_solr c:pull_replica_error_handling_test_cant_connect_to_leader s:shard1 r:core_node1 x:pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node1&collection.configName=conf&newCollection=true&name=pull_replica_error_handling_test_cant_connect_to_leader_shard1_replica_n1&action=CREATE&numShards=1&collection=pull_replica_error_handling_test_cant_connect_to_leader&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1336
[junit4] 2> 1735497 INFO (zkCallback-1661-thread-2-processing-n:127.0.0.1:35134_solr) [n:127.0.0.1:35134_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json] for collection [pull_replica_error_handling_test_cant_connect_to_leader] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1735497 INFO (zkCallback-1643-thread-1-processing-n:127.0.0.1:36986_solr) [n:127.0.0.1:36986_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json] for collection [pull_replica_error_handling_test_cant_connect_to_leader] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1735497 INFO (zkCallback-1661-thread-1-processing-n:127.0.0.1:35134_solr) [n:127.0.0.1:35134_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/pull_replica_error_handling_test_cant_connect_to_leader/state.json] for collection [pull_replica_error_handling_test_cant_connect_to_leader] has occurred - updating... (
[...truncated too long message...]
am, numWorkers, comp);
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'parallelStream' is never closed
[ecj-lint] ----------
[ecj-lint] 8. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/handler/sql/SolrTable.java (at line 743)
[ecj-lint] ParallelStream parallelStream = new ParallelStream(zkHost, collection, tupleStream, numWorkers, comp);
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'parallelStream' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 9. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/highlight/DefaultSolrHighlighter.java (at line 578)
[ecj-lint] tvWindowStream = new OffsetWindowTokenFilter(tvStream);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'tvWindowStream' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 10. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/request/SimpleFacets.java (at line 912)
[ecj-lint] fastForRandomSet = new HashDocSet(sset.getDocs(), 0, sset.size());
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'fastForRandomSet' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 11. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/response/SmileResponseWriter.java (at line 33)
[ecj-lint] new SmileWriter(out, request, response).writeResponse();
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 12. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/schema/OpenExchangeRatesOrgProvider.java (at line 146)
[ecj-lint] ratesJsonStream = resourceLoader.openResource(ratesFileLocation);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'ratesJsonStream' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 13. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/search/CollapsingQParserPlugin.java (at line 1313)
[ecj-lint] UninvertingReader uninvertingReader = new UninvertingReader(new ReaderWrapper(searcher.getSlowAtomicReader(), collapseField), mapping);
[ecj-lint] ^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'uninvertingReader' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 14. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 145)
[ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len);
[ecj-lint] ^
[ecj-lint] Resource leak: 'h' is never closed
[ecj-lint] ----------
[ecj-lint] 15. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 154)
[ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len);
[ecj-lint] ^
[ecj-lint] Resource leak: 'h' is never closed
[ecj-lint] ----------
[ecj-lint] 16. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 163)
[ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len);
[ecj-lint] ^
[ecj-lint] Resource leak: 'h' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 17. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/search/function/OrdFieldSource.java (at line 78)
[ecj-lint] SolrIndexSearcher is = (SolrIndexSearcher) o;
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 18. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/search/function/ReverseOrdFieldSource.java (at line 78)
[ecj-lint] SolrIndexSearcher is = (SolrIndexSearcher) o;
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 19. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/servlet/SolrDispatchFilter.java (at line 287)
[ecj-lint] throw new SolrException(ErrorCode.SERVER_ERROR, "Error occurred while loading solr.xml from zookeeper", e);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'loader' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 20. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/update/CdcrTransactionLog.java (at line 98)
[ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos);
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 21. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/update/HdfsTransactionLog.java (at line 191)
[ecj-lint] LogCodec codec = new LogCodec(resolver);
[ecj-lint] ^^^^^
[ecj-lint] Resource leak: 'codec' is never closed
[ecj-lint] ----------
[ecj-lint] 22. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/update/HdfsTransactionLog.java (at line 261)
[ecj-lint] LogCodec codec = new LogCodec(resolver);
[ecj-lint] ^^^^^
[ecj-lint] Resource leak: 'codec' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 23. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 228)
[ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos);
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] 24. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 228)
[ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos);
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] 25. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 260)
[ecj-lint] LogCodec codec = new LogCodec(resolver);
[ecj-lint] ^^^^^
[ecj-lint] Resource leak: 'codec' is never closed
[ecj-lint] ----------
[ecj-lint] 26. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 275)
[ecj-lint] LogCodec codec = new LogCodec(resolver);
[ecj-lint] ^^^^^
[ecj-lint] Resource leak: 'codec' is never closed
[ecj-lint] ----------
[ecj-lint] 27. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 529)
[ecj-lint] LogCodec codec = new LogCodec(resolver);
[ecj-lint] ^^^^^
[ecj-lint] Resource leak: 'codec' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 28. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/update/processor/UpdateRequestProcessor.java (at line 85)
[ecj-lint] UpdateRequestProcessor p = this;
[ecj-lint] ^
[ecj-lint] Resource leak: 'p' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 29. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/util/SimplePostTool.java (at line 655)
[ecj-lint] BAOS bos = new BAOS();
[ecj-lint] ^^^
[ecj-lint] Resource leak: 'bos' is never closed
[ecj-lint] ----------
[ecj-lint] 30. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/util/SimplePostTool.java (at line 655)
[ecj-lint] BAOS bos = new BAOS();
[ecj-lint] ^^^
[ecj-lint] Resource leak: 'bos' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 31. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/java/org/apache/solr/util/SolrLogLayout.java (at line 149)
[ecj-lint] SolrQueryRequest req = requestInfo == null ? null : requestInfo.getReq();
[ecj-lint] ^^^
[ecj-lint] Resource leak: 'req' is never closed
[ecj-lint] ----------
[ecj-lint] 31 problems (1 error, 30 warnings)
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:810: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:101: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build.xml:689: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/common-build.xml:2004: The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/common-build.xml:2043: Compile failed; see the compiler error output for details.
Total time: 145 minutes 45 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-Tests-7.x - Build # 45 - Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/45/
1 tests failed.
FAILED: org.apache.solr.request.SimpleFacetsTest.testDvMethodNegativeFloatRangeFacet
Error Message:
Unexpected schema configuration expected: com.fasterxml.jackson.core.JsonParser$NumberType<FLOAT> but was: org.apache.solr.schema.NumberType<FLOAT>
Stack Trace:
java.lang.AssertionError: Unexpected schema configuration expected: com.fasterxml.jackson.core.JsonParser$NumberType<FLOAT> but was: org.apache.solr.schema.NumberType<FLOAT>
at __randomizedtesting.SeedInfo.seed([B281B9B8B0EE1851:9E22E696CDDA0037]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.apache.solr.request.SimpleFacetsTest.testDvMethodNegativeFloatRangeFacet(SimpleFacetsTest.java:190)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12067 lines...]
[junit4] Suite: org.apache.solr.request.SimpleFacetsTest
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.request.SimpleFacetsTest_B281B9B8B0EE1851-001/init-core-data-001
[junit4] 2> 1254775 WARN (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=27 numCloses=27
[junit4] 2> 1254776 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1254777 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1254778 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1254778 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1254807 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1254837 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1254934 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1254974 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f06844
[junit4] 2> 1254979 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f06844
[junit4] 2> 1254979 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14f06844
[junit4] 2> 1254981 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1255028 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1255050 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1255152 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1255159 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1255159 INFO (coreLoadExecutor-6655-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@14f06844
[junit4] 2> 1255160 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1255160 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.request.SimpleFacetsTest_B281B9B8B0EE1851-001/init-core-data-001/]
[junit4] 2> 1255161 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8628187906591435]
[junit4] 2> 1255224 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1255224 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1255226 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1255226 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1255227 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=34, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5597527305498623]
[junit4] 2> 1255227 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@39661e41[collection1] main]
[junit4] 2> 1255228 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1255235 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1255261 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1255262 INFO (searcherExecutor-6656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1255262 INFO (searcherExecutor-6656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1255262 INFO (searcherExecutor-6656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1255262 INFO (searcherExecutor-6656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1255262 INFO (searcherExecutor-6656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1255263 INFO (searcherExecutor-6656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1255263 INFO (searcherExecutor-6656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1255263 INFO (searcherExecutor-6656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1255263 INFO (searcherExecutor-6656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1255264 INFO (searcherExecutor-6656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@39661e41[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1255264 INFO (coreLoadExecutor-6655-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1572840404519223296
[junit4] 2> 1255268 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1255269 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1255269 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t} hits=0 status=0 QTime=0
[junit4] 2> 1255270 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255270 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1255270 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255271 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1255271 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255271 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1255271 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255272 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1255272 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255272 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1255272 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255273 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1255273 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255273 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1255274 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255274 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1255274 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255275 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=empty_t,memSize=4320,tindexSize=32,time=0,phase1=0,nTerms=0,bigTerms=0,termInstances=0,uses=0}
[junit4] 2> 1255275 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1255275 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255276 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1255276 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255276 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1255276 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255279 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=3
[junit4] 2> 1255280 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i} hits=0 status=0 QTime=0
[junit4] 2> 1255280 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255280 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1255285 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=fc&facet.missing=true} hits=0 status=0 QTime=4
[junit4] 2> 1255285 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1255285 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=enum&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255286 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=empty_i,memSize=4320,tindexSize=32,time=0,phase1=0,nTerms=0,bigTerms=0,termInstances=0,uses=0}
[junit4] 2> 1255286 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1255286 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=uif&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255286 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1255286 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f} hits=0 status=0 QTime=0
[junit4] 2> 1255296 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255296 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1255296 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=fc&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255297 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1255297 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=enum&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255297 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=empty_f,memSize=4320,tindexSize=32,time=0,phase1=0,nTerms=0,bigTerms=0,termInstances=0,uses=0}
[junit4] 2> 1255297 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1255298 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=uif&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255298 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1255298 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s} hits=0 status=0 QTime=0
[junit4] 2> 1255298 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255299 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1255299 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255299 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1255299 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255299 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1255300 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255300 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1255300 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255300 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1255301 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255301 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1255301 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255301 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1255301 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255302 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1255302 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255302 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=empty_s,memSize=4320,tindexSize=32,time=0,phase1=0,nTerms=0,bigTerms=0,termInstances=0,uses=0}
[junit4] 2> 1255302 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1255303 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255303 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1255303 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255303 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1255304 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255304 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1255304 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d} hits=0 status=0 QTime=0
[junit4] 2> 1255304 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255307 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1255307 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=fc&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255307 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1255307 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=enum&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255308 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=empty_d,memSize=4320,tindexSize=32,time=0,phase1=0,nTerms=0,bigTerms=0,termInstances=0,uses=0}
[junit4] 2> 1255308 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1255308 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=uif&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1255309 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404566409216,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255309 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1255310 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255310 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1255312 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404569554944,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255312 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1255312 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255313 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1255313 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404570603520,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255313 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1255313 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255313 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1255313 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404570603521,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255313 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1255314 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255314 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1255316 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[701 (1572840404571652096)]} 0 1
[junit4] 2> 1255317 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404574797824,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255317 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404574797824
[junit4] 2> 1255322 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@22da11f4[collection1] main]
[junit4] 2> 1255323 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255325 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@22da11f4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1)))}
[junit4] 2> 1255325 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 8
[junit4] 2> 1255328 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5010 (1572840404583186432)]} 0 3
[junit4] 2> 1255330 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2004 (1572840404587380736)]} 0 0
[junit4] 2> 1255331 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6012 (1572840404588429312)]} 0 0
[junit4] 2> 1255332 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[403 (1572840404589477888)]} 0 0
[junit4] 2> 1255333 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404590526464,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255333 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404590526464
[junit4] 2> 1255336 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7f1d5aa0[collection1] main]
[junit4] 2> 1255339 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7f1d5aa0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4)))}
[junit4] 2> 1255339 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255339 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 1255341 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[805 (1572840404597866496)]} 0 1
[junit4] 2> 1255342 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[401 (1572840404599963648)]} 0 0
[junit4] 2> 1255342 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[801 (1572840404601012224)]} 0 0
[junit4] 2> 1255346 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType dateRange{class=org.apache.solr.schema.DateRangeField,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args={class=solr.DateRangeField}} strat: NumberRangePrefixTreeStrategy(SPG:(DateRangePrefixTree),prefixGridScanLevel:7) maxLevels: 11
[junit4] 2> 1255354 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType dateRange{class=org.apache.solr.schema.DateRangeField,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args={class=solr.DateRangeField}} strat: NumberRangePrefixTreeStrategy(SPG:(DateRangePrefixTree),prefixGridScanLevel:7) maxLevels: 11
[junit4] 2> 1255368 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[207 (1572840404602060800)]} 0 25
[junit4] 2> 1255369 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4012 (1572840404628275200)]} 0 0
[junit4] 2> 1255369 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404629323776,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255369 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404629323776
[junit4] 2> 1255373 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@449b8fad[collection1] main]
[junit4] 2> 1255373 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255375 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@449b8fad[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5)))}
[junit4] 2> 1255375 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 1255377 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[401 (1572840404636663808)]} 0 1
[junit4] 2> 1255378 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[404 (1572840404637712384)]} 0 0
[junit4] 2> 1255378 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404638760960,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255378 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404638760960
[junit4] 2> 1255381 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a130e0f[collection1] main]
[junit4] 2> 1255381 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255383 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5a130e0f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/1:delGen=1) Uninverting(_3(7.1.0):c2)))}
[junit4] 2> 1255383 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 1255385 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6011 (1572840404645052416)]} 0 1
[junit4] 2> 1255386 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[404 (1572840404646100992)]} 0 0
[junit4] 2> 1255386 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404647149568,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255386 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404647149568
[junit4] 2> 1255389 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b8ac1bf[collection1] main]
[junit4] 2> 1255389 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255391 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4b8ac1bf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/1:delGen=1) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2)))}
[junit4] 2> 1255391 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 1255393 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6011 (1572840404652392448)]} 0 1
[junit4] 2> 1255395 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[47 (1572840404654489600)]} 0 2
[junit4] 2> 1255395 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404656586752,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255395 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404656586752
[junit4] 2> 1255399 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e0cce30[collection1] main]
[junit4] 2> 1255400 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255402 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e0cce30[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/1:delGen=1) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2)))}
[junit4] 2> 1255402 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 1255404 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[605 (1572840404664975360)]} 0 1
[junit4] 2> 1255405 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6010 (1572840404667072512)]} 0 0
[junit4] 2> 1255406 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8012 (1572840404668121088)]} 0 0
[junit4] 2> 1255406 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404668121089,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255407 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404668121089
[junit4] 2> 1255410 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@64200454[collection1] main]
[junit4] 2> 1255410 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255412 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@64200454[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/1:delGen=1) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2) Uninverting(_6(7.1.0):c3)))}
[junit4] 2> 1255412 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 1255416 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[47 (1572840404675461120)]} 0 3
[junit4] 2> 1255416 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404678606848,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255416 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404678606848
[junit4] 2> 1255420 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@185d573[collection1] main]
[junit4] 2> 1255421 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255423 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@185d573[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/1:delGen=1) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_7(7.1.0):c1)))}
[junit4] 2> 1255424 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7
[junit4] 2> 1255425 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[609 (1572840404686995456)]} 0 1
[junit4] 2> 1255426 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[607 (1572840404689092608)]} 0 0
[junit4] 2> 1255427 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[707 (1572840404689092609)]} 0 0
[junit4] 2> 1255428 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[708 (1572840404690141184)]} 0 0
[junit4] 2> 1255429 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[102 (1572840404691189760)]} 0 0
[junit4] 2> 1255429 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404692238336,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255429 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404692238336
[junit4] 2> 1255432 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5af3ec01[collection1] main]
[junit4] 2> 1255433 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255435 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5af3ec01[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/1:delGen=1) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_7(7.1.0):c1) Uninverting(_8(7.1.0):c5)))}
[junit4] 2> 1255435 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 1255438 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[46 (1572840404698529792)]} 0 2
[junit4] 2> 1255440 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[206 (1572840404701675520)]} 0 2
[junit4] 2> 1255442 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2013 (1572840404704821248)]} 0 1
[junit4] 2> 1255443 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[507 (1572840404705869824)]} 0 0
[junit4] 2> 1255443 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[601 (1572840404706918400)]} 0 0
[junit4] 2> 1255445 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[46 (1572840404707966976)]} 0 1
[junit4] 2> 1255446 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[708 (1572840404710064128)]} 0 0
[junit4] 2> 1255447 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[608 (1572840404710064129)]} 0 0
[junit4] 2> 1255448 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[802 (1572840404711112704)]} 0 0
[junit4] 2> 1255448 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404712161280,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255448 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404712161280
[junit4] 2> 1255454 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@56936955[collection1] main]
[junit4] 2> 1255454 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255456 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@56936955[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/1:delGen=1) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_7(7.1.0):c1) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/1:delGen=1)))}
[junit4] 2> 1255456 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 8
[junit4] 2> 1255459 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[45 (1572840404721598464)]} 0 2
[junit4] 2> 1255460 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[508 (1572840404724744192)]} 0 0
[junit4] 2> 1255461 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404724744193,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255461 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404724744193
[junit4] 2> 1255465 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@167e2ef1[collection1] main]
[junit4] 2> 1255466 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255468 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@167e2ef1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/1:delGen=1) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_7(7.1.0):c1) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/1:delGen=1) Uninverting(_a(7.1.0):c2)))}
[junit4] 2> 1255468 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7
[junit4] 2> 1255470 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5011 (1572840404733132800)]} 0 1
[junit4] 2> 1255471 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[604 (1572840404735229952)]} 0 0
[junit4] 2> 1255471 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404736278528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255471 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404736278528
[junit4] 2> 1255475 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6c2e0b04[collection1] main]
[junit4] 2> 1255476 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255478 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6c2e0b04[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/1:delGen=1) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_7(7.1.0):c1) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/1:delGen=1) Uninverting(_a(7.1.0):c2) Uninverting(_b(7.1.0):c2)))}
[junit4] 2> 1255478 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 1255479 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[706 (1572840404743618560)]} 0 1
[junit4] 2> 1255483 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[207 (1572840404745715712)]} 0 3
[junit4] 2> 1255484 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[603 (1572840404749910016)]} 0 0
[junit4] 2> 1255485 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[503 (1572840404750958592)]} 0 0
[junit4] 2> 1255486 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[602 (1572840404750958593)]} 0 0
[junit4] 2> 1255487 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[801 (1572840404752007168)]} 0 0
[junit4] 2> 1255488 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[503 (1572840404753055744)]} 0 0
[junit4] 2> 1255488 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404754104320,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255488 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404754104320
[junit4] 2> 1255492 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7c316cbd[collection1] main]
[junit4] 2> 1255493 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255495 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7c316cbd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/3:delGen=2) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_7(7.1.0):c1) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/1:delGen=1) Uninverting(_a(7.1.0):c2) Uninverting(_b(7.1.0):c2) Uninverting(_c(7.1.0):c7/1:delGen=1)))}
[junit4] 2> 1255495 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 1255496 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[502 (1572840404761444352)]} 0 1
[junit4] 2> 1255497 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404763541504,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255497 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404763541504
[junit4] 2> 1255509 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@63a33e2d[collection1] main]
[junit4] 2> 1255511 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@63a33e2d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/3:delGen=2) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_7(7.1.0):c1) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/1:delGen=1) Uninverting(_a(7.1.0):c2) Uninverting(_b(7.1.0):c2) Uninverting(_c(7.1.0):c7/1:delGen=1) Uninverting(_d(7.1.0):c1)))}
[junit4] 2> 1255512 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255512 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 15
[junit4] 2> 1255514 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[809 (1572840404779270144)]} 0 1
[junit4] 2> 1255515 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[406 (1572840404781367296)]} 0 0
[junit4] 2> 1255515 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[801 (1572840404782415872)]} 0 0
[junit4] 2> 1255516 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404783464448,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255516 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404783464448
[junit4] 2> 1255519 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e3e1809[collection1] main]
[junit4] 2> 1255522 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e3e1809[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4) Uninverting(_2(7.1.0):c5/3:delGen=2) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_7(7.1.0):c1) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/1:delGen=1) Uninverting(_a(7.1.0):c2) Uninverting(_b(7.1.0):c2) Uninverting(_c(7.1.0):c7/2:delGen=2) Uninverting(_d(7.1.0):c1) Uninverting(_e(7.1.0):c3)))}
[junit4] 2> 1255522 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255522 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 1255524 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[505 (1572840404790804480)]} 0 1
[junit4] 2> 1255525 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5011 (1572840404791853056)]} 0 0
[junit4] 2> 1255526 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[47 (1572840404792901632)]} 0 1
[junit4] 2> 1255527 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6012 (1572840404793950208)]} 0 0
[junit4] 2> 1255529 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[43 (1572840404794998784)]} 0 1
[junit4] 2> 1255530 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4014 (1572840404797095936)]} 0 0
[junit4] 2> 1255530 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404798144512,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255530 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404798144512
[junit4] 2> 1255535 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@62b0bbc5[collection1] main]
[junit4] 2> 1255537 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@62b0bbc5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4/1:delGen=1) Uninverting(_2(7.1.0):c5/3:delGen=2) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/1:delGen=1) Uninverting(_a(7.1.0):c2) Uninverting(_b(7.1.0):c2/1:delGen=1) Uninverting(_c(7.1.0):c7/2:delGen=2) Uninverting(_d(7.1.0):c1) Uninverting(_e(7.1.0):c3) Uninverting(_f(7.1.0):c6)))}
[junit4] 2> 1255538 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255538 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 8
[junit4] 2> 1255540 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2012 (1572840404806533120)]} 0 2
[junit4] 2> 1255542 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[204 (1572840404809678848)]} 0 1
[junit4] 2> 1255543 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5010 (1572840404811776000)]} 0 0
[junit4] 2> 1255544 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404811776001,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255544 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404811776001
[junit4] 2> 1255548 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@61d1323d[collection1] main]
[junit4] 2> 1255550 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@61d1323d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4/2:delGen=2) Uninverting(_2(7.1.0):c5/3:delGen=2) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/1:delGen=1) Uninverting(_a(7.1.0):c2) Uninverting(_b(7.1.0):c2/1:delGen=1) Uninverting(_c(7.1.0):c7/2:delGen=2) Uninverting(_d(7.1.0):c1) Uninverting(_e(7.1.0):c3) Uninverting(_f(7.1.0):c6) Uninverting(_g(7.1.0):c3)))}
[junit4] 2> 1255550 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255550 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 1255552 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[807 (1572840404820164608)]} 0 1
[junit4] 2> 1255553 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7011 (1572840404821213184)]} 0 0
[junit4] 2> 1255554 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[806 (1572840404822261760)]} 0 0
[junit4] 2> 1255555 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2011 (1572840404823310336)]} 0 1
[junit4] 2> 1255556 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[101 (1572840404825407488)]} 0 0
[junit4] 2> 1255557 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2003 (1572840404825407489)]} 0 0
[junit4] 2> 1255558 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[504 (1572840404826456064)]} 0 0
[junit4] 2> 1255559 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7010 (1572840404827504640)]} 0 0
[junit4] 2> 1255561 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[46 (1572840404828553216)]} 0 1
[junit4] 2> 1255575 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[204 (1572840404830650368)]} 0 14
[junit4] 2> 1255576 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5012 (1572840404846379008)]} 0 0
[junit4] 2> 1255577 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[509 (1572840404847427584)]} 0 0
[junit4] 2> 1255578 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[804 (1572840404848476160)]} 0 0
[junit4] 2> 1255579 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[709 (1572840404848476161)]} 0 0
[junit4] 2> 1255579 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404849524736,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255579 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404849524736
[junit4] 2> 1255589 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b8e9389[collection1] main]
[junit4] 2> 1255592 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5b8e9389[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4/2:delGen=2) Uninverting(_2(7.1.0):c5/3:delGen=2) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/2:delGen=2) Uninverting(_a(7.1.0):c2) Uninverting(_b(7.1.0):c2/1:delGen=1) Uninverting(_c(7.1.0):c7/2:delGen=2) Uninverting(_d(7.1.0):c1) Uninverting(_e(7.1.0):c3) Uninverting(_f(7.1.0):c6) Uninverting(_g(7.1.0):c3/1:delGen=1) Uninverting(_h(7.1.0):c14)))}
[junit4] 2> 1255592 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255592 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 13
[junit4] 2> 1255594 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[704 (1572840404864204800)]} 0 1
[junit4] 2> 1255595 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8011 (1572840404865253376)]} 0 0
[junit4] 2> 1255595 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404866301952,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255595 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404866301952
[junit4] 2> 1255599 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@261dd8c2[collection1] main]
[junit4] 2> 1255602 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@261dd8c2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4/2:delGen=2) Uninverting(_2(7.1.0):c5/3:delGen=2) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/2:delGen=2) Uninverting(_a(7.1.0):c2) Uninverting(_b(7.1.0):c2/1:delGen=1) Uninverting(_c(7.1.0):c7/2:delGen=2) Uninverting(_d(7.1.0):c1) Uninverting(_e(7.1.0):c3) Uninverting(_f(7.1.0):c6) Uninverting(_g(7.1.0):c3/1:delGen=1) Uninverting(_h(7.1.0):c14) Uninverting(_i(7.1.0):c2)))}
[junit4] 2> 1255602 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255602 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 1255604 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4011 (1572840404873641984)]} 0 1
[junit4] 2> 1255606 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[209 (1572840404875739136)]} 0 1
[junit4] 2> 1255607 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[501 (1572840404877836288)]} 0 0
[junit4] 2> 1255608 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[604 (1572840404878884864)]} 0 0
[junit4] 2> 1255608 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404879933440,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255608 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404879933440
[junit4] 2> 1255612 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2a00f906[collection1] main]
[junit4] 2> 1255615 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2a00f906[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4/2:delGen=2) Uninverting(_2(7.1.0):c5/3:delGen=2) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_5(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/2:delGen=2) Uninverting(_a(7.1.0):c2) Uninverting(_c(7.1.0):c7/2:delGen=2) Uninverting(_d(7.1.0):c1) Uninverting(_e(7.1.0):c3) Uninverting(_f(7.1.0):c6) Uninverting(_g(7.1.0):c3/1:delGen=1) Uninverting(_h(7.1.0):c14) Uninverting(_i(7.1.0):c2) Uninverting(_j(7.1.0):c4)))}
[junit4] 2> 1255615 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1255615 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7
[junit4] 2> 1255618 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[45 (1572840404888322048)]} 0 2
[junit4] 2> 1255619 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6011 (1572840404890419200)]} 0 0
[junit4] 2> 1255619 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572840404891467776,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1255619 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@3303b9ac commitCommandVersion:1572840404891467776
[junit4] 2> 1255624 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5e8aba48[collection1] main]
[junit4] 2> 1255626 INFO (searcherExecutor-6656-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5e8aba48[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c1) Uninverting(_1(7.1.0):c4/2:delGen=2) Uninverting(_2(7.1.0):c5/3:delGen=2) Uninverting(_3(7.1.0):c2/1:delGen=1) Uninverting(_4(7.1.0):c2/1:delGen=1) Uninverting(_6(7.1.0):c3) Uninverting(_8(7.1.0):c5/1:delGen=1) Uninverting(_9(7.1.0):c9/2:delGen=2) Uninverting(_a(7.1.0):c2/1:delGen=1) Uninverting(_c(7.1.0):c7/2:delGen=2) Uninverting(_d(7.1.0):c1) Uninverting(_e(7.1.0):c3) Uninverting(_f(7.1.0):c6) Uninverting(_g(7.1.0):c3/1:delGen=1) Uninverting(_h(7.1.0):c14) Uninverting(_i(7.1.0):c2) Uninverting(_j(7.1.0):c4) Uninverting(_k(7
[...truncated too long message...]
offset=0&facet.sort=false} hits=4 status=0 QTime=0
[junit4] 2> 1259013 INFO (TEST-SimpleFacetsTest.testSimpleFacetCounts-seed#[B281B9B8B0EE1851]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id_i1:[42+TO+47]&facet.field=zerolen_s&f.zerolen_s.facet.method=fc&fq=id_i1:[42+TO+45]&facet=true&wt=xml} hits=4 status=0 QTime=0
[junit4] 2> 1259014 INFO (TEST-SimpleFacetsTest.testSimpleFacetCounts-seed#[B281B9B8B0EE1851]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id_i1:[42+TO+47]&facet.field=zerolen_s&f.zerolen_s.facet.method=uif&fq=id_i1:[42+TO+45]&facet=true&wt=xml} hits=4 status=0 QTime=0
[junit4] 2> 1259016 INFO (TEST-SimpleFacetsTest.testSimpleFacetCounts-seed#[B281B9B8B0EE1851]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query={!field+key%3Dk+f%3Dlengthfilt}a&q=*:*&facet=true&wt=xml} hits=89 status=0 QTime=0
[junit4] 2> 1259016 INFO (TEST-SimpleFacetsTest.testSimpleFacetCounts-seed#[B281B9B8B0EE1851]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSimpleFacetCounts
[junit4] 2> 1259019 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRangeFacetInfiniteLoopDetection
[junit4] 2> 1259019 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching .
[junit4] 2> 1259019 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.range=foo_f&q=*:*&facet.range.gap=0&facet=true&wt=xml&facet.range.start=23&facet.range.end=100} status=400 QTime=0
[junit4] 2> 1259020 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching .
[junit4] 2> 1259020 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.range=foo_d&q=*:*&facet.range.gap=0&facet=true&wt=xml&facet.range.start=23&facet.range.end=100} status=400 QTime=0
[junit4] 2> 1259020 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching .
[junit4] 2> 1259020 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.range=foo_i&q=*:*&facet.range.gap=0&facet=true&wt=xml&facet.range.start=23&facet.range.end=100} status=400 QTime=0
[junit4] 2> 1259020 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching .
[junit4] 2> 1259021 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.range=foo_dt&q=*:*&facet.range.gap=%2B0DAYS&facet=true&wt=xml&facet.range.start=NOW&facet.range.end=NOW%2B10DAY} status=400 QTime=0
[junit4] 2> 1259021 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching .
[junit4] 2> 1259021 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.range=foo_f&q=*:*&facet.range.gap=2160&facet=true&wt=xml&facet.range.start=100000000000&facet.range.end=100000086200} status=400 QTime=0
[junit4] 2> 1259021 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching .
[junit4] 2> 1259021 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.range=foo_d&q=*:*&facet.range.gap=0.0003&facet=true&wt=xml&facet.range.start=9900000000000&facet.range.end=9900000086200} status=400 QTime=0
[junit4] 2> 1259022 INFO (TEST-SimpleFacetsTest.testRangeFacetInfiniteLoopDetection-seed#[B281B9B8B0EE1851]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRangeFacetInfiniteLoopDetection
[junit4] 2> 1259022 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1259022 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1958376208
[junit4] 2> 1259022 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1259032 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1259034 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1259034 INFO (SUITE-SimpleFacetsTest-seed#[B281B9B8B0EE1851]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1259037 INFO (coreCloseExecutor-6661-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@21e8bf4c
[junit4] 2> 1259039 INFO (coreCloseExecutor-6661-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=568901452
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.request.SimpleFacetsTest_B281B9B8B0EE1851-001
[junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=2, maxDocsPerChunk=1, blockSize=414), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=2, blockSize=414)), sim=RandomSimilarity(queryNorm=false): {}, locale=hi-IN, timezone=America/Hermosillo
[junit4] 2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=4,threads=1,free=228173672,total=532676608
[junit4] 2> NOTE: All tests run in this JVM: [TestTestInjection, HighlighterTest, JavabinLoaderTest, ZkSolrClientTest, TestConfigSetsAPIExclusivity, RestartWhileUpdatingTest, DistributedMLTComponentTest, TestCloudPivotFacet, TestFieldSortValues, ManagedSchemaRoundRobinCloudTest, TestLockTree, DistributedTermsComponentTest, ClusterStateUpdateTest, LoggingHandlerTest, TestFuzzyAnalyzedSuggestions, TestPayloadScoreQParserPlugin, TestScoreJoinQPNoScore, OverseerModifyCollectionTest, TestNumericTerms64, SpatialRPTFieldTypeTest, StatelessScriptUpdateProcessorFactoryTest, SystemInfoHandlerTest, ZkStateReaderTest, PrimUtilsTest, OpenCloseCoreStressTest, TestRandomRequestDistribution, SuggesterTSTTest, SpatialFilterTest, ClassificationUpdateProcessorFactoryTest, TestElisionMultitermQuery, TestCursorMarkWithoutUniqueKey, OutOfBoxZkACLAndCredentialsProvidersTest, TestMiniSolrCloudClusterSSL, HdfsRecoveryZkTest, TestCloudDeleteByQuery, TestHashPartitioner, SolrMetricsIntegrationTest, AsyncCallRequestStatusResponseTest, BlockJoinFacetDistribTest, ConjunctionSolrSpellCheckerTest, TestDeleteCollectionOnDownNodes, DeleteNodeTest, TestTlogReplica, OverseerTaskQueueTest, TestConfigOverlay, SortSpecParsingTest, CoreAdminCreateDiscoverTest, InfoHandlerTest, TestStressVersions, AlternateDirectoryTest, UUIDUpdateProcessorFallbackTest, TestExactSharedStatsCache, CoreAdminHandlerTest, TestTrieFacet, TestOrdValues, TestHighFrequencyDictionaryFactory, AutoScalingHandlerTest, ShardRoutingTest, HdfsWriteToMultipleCollectionsTest, TestSimpleTrackingShardHandler, TestSerializedLuceneMatchVersion, TestCoreDiscovery, CdcrVersionReplicationTest, CacheHeaderTest, HdfsThreadLeakTest, TestDynamicLoading, CollectionReloadTest, ShufflingReplicaListTransformerTest, TestReqParamsAPI, TestSearchPerf, SecurityConfHandlerTest, TestJsonFacetRefinement, BadComponentTest, BasicAuthIntegrationTest, SolrCoreMetricManagerTest, TestAtomicUpdateErrorCases, TestHashQParserPlugin, DistributedQueryComponentCustomSortTest, ZkCLITest, TestBlendedInfixSuggestions, TestInPlaceUpdatesStandalone, TestApiFramework, TestFastLRUCache, SolrShardReporterTest, HdfsCollectionsAPIDistributedZkTest, TestStressRecovery, TestLegacyNumericUtils, ShardRoutingCustomTest, TestHttpShardHandlerFactory, LeaderInitiatedRecoveryOnCommitTest, LukeRequestHandlerTest, TestIntervalFaceting, TestConfigSets, ZkControllerTest, PeerSyncWithIndexFingerprintCachingTest, TestLeaderElectionZkExpiry, TestPullReplica, SharedFSAutoReplicaFailoverUtilsTest, BooleanFieldTest, TestFieldTypeCollectionResource, RemoteQueryErrorTest, SOLR749Test, CdcrReplicationDistributedZkTest, ConvertedLegacyTest, LargeFieldTest, TestCoreAdminApis, TermVectorComponentTest, DistributedIntervalFacetingTest, TestSystemIdResolver, ExplicitHLLTest, DirectSolrSpellCheckerTest, MissingSegmentRecoveryTest, CSVRequestHandlerTest, AnalyticsMergeStrategyTest, RuleEngineTest, TestNumericRangeQuery32, TestSolrQueryResponse, CloneFieldUpdateProcessorFactoryTest, TestManagedResourceStorage, DistanceFunctionTest, DirectSolrConnectionTest, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, LeaderElectionIntegrationTest, BasicZkTest, TestReplicationHandler, AutoCommitTest, TestFaceting, TestRecovery, TestReload, TestCoreContainer, SimpleFacetsTest]
[junit4] Completed [376/728 (1!)] on J1 in 4.29s, 44 tests, 1 failure <<< FAILURES!
[...truncated 44473 lines...]