Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4442/
Java: 64bit/jdk-9 -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED: org.apache.solr.cloud.CloudExitableDirectoryReaderTest.test
Error Message:
Time allowed to handle this request exceeded
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this
request exceeded
at
__randomizedtesting.SeedInfo.seed([542CB894D9B201B6:DC78874E774E6C4E]:0)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1104)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.assertPartialResults(CloudExitableDirectoryReaderTest.java:106)
at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTimeoutTests(CloudExitableDirectoryReaderTest.java:78)
at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.test(CloudExitableDirectoryReaderTest.java:56)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory
Error Message:
expected:<5> but was:<0>
Stack Trace:
java.lang.AssertionError: expected:<5> but was:<0>
at
__randomizedtesting.SeedInfo.seed([542CB894D9B201B6:39D01C6963FAFEB1]: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.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory(AutoscalingHistoryHandlerTest.java:274)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12357 lines...]
[junit4] Suite: org.apache.solr.cloud.CloudExitableDirectoryReaderTest
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_542CB894D9B201B6-001/init-core-data-001
[junit4] 2> 624877 WARN
(SUITE-CloudExitableDirectoryReaderTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 624877 INFO
(SUITE-CloudExitableDirectoryReaderTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 624878 INFO
(SUITE-CloudExitableDirectoryReaderTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0,
clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 624878 INFO
(SUITE-CloudExitableDirectoryReaderTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 624878 INFO
(SUITE-CloudExitableDirectoryReaderTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_542CB894D9B201B6-001/tempDir-001
[junit4] 2> 624878 INFO
(SUITE-CloudExitableDirectoryReaderTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 624879 INFO (Thread-1267) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 624879 INFO (Thread-1267) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 624883 ERROR (Thread-1267) [ ] 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> 624982 INFO
(SUITE-CloudExitableDirectoryReaderTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:62198
[junit4] 2> 624999 INFO (zkConnectionManagerCallback-1099-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 625002 INFO (jetty-launcher-1096-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 625002 INFO (jetty-launcher-1096-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 625005 INFO (jetty-launcher-1096-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 625005 INFO (jetty-launcher-1096-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 625005 INFO (jetty-launcher-1096-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 625005 INFO (jetty-launcher-1096-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 625005 INFO (jetty-launcher-1096-thread-1) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 625005 INFO (jetty-launcher-1096-thread-2) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 625005 INFO (jetty-launcher-1096-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@46b3d206{/solr,null,AVAILABLE}
[junit4] 2> 625005 INFO (jetty-launcher-1096-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@500bc96{/solr,null,AVAILABLE}
[junit4] 2> 625006 INFO (jetty-launcher-1096-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@374a2b70{HTTP/1.1,[http/1.1]}{127.0.0.1:62201}
[junit4] 2> 625006 INFO (jetty-launcher-1096-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@76b37be1{HTTP/1.1,[http/1.1]}{127.0.0.1:62200}
[junit4] 2> 625006 INFO (jetty-launcher-1096-thread-2) [ ]
o.e.j.s.Server Started @627930ms
[junit4] 2> 625006 INFO (jetty-launcher-1096-thread-1) [ ]
o.e.j.s.Server Started @627930ms
[junit4] 2> 625006 INFO (jetty-launcher-1096-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=62201}
[junit4] 2> 625006 INFO (jetty-launcher-1096-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=62200}
[junit4] 2> 625007 ERROR (jetty-launcher-1096-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 625007 ERROR (jetty-launcher-1096-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 625007 INFO (jetty-launcher-1096-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 625007 INFO (jetty-launcher-1096-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 625007 INFO (jetty-launcher-1096-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 625007 INFO (jetty-launcher-1096-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 625007 INFO (jetty-launcher-1096-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 625007 INFO (jetty-launcher-1096-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 625007 INFO (jetty-launcher-1096-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-15T16:32:31.892302Z
[junit4] 2> 625007 INFO (jetty-launcher-1096-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-15T16:32:31.892320Z
[junit4] 2> 625009 INFO (zkConnectionManagerCallback-1103-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 625009 INFO (zkConnectionManagerCallback-1102-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 625009 INFO (jetty-launcher-1096-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 625010 INFO (jetty-launcher-1096-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 625014 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1000731492c0002, likely client has closed socket
[junit4] 2> 625015 INFO (jetty-launcher-1096-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62198/solr
[junit4] 2> 625016 INFO (jetty-launcher-1096-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62198/solr
[junit4] 2> 625018 INFO (zkConnectionManagerCallback-1109-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 625018 INFO (zkConnectionManagerCallback-1111-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 625019 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1000731492c0003, likely client has closed socket
[junit4] 2> 625020 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1000731492c0004, likely client has closed socket
[junit4] 2> 625022 INFO
(zkConnectionManagerCallback-1113-thread-1-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 625022 INFO
(zkConnectionManagerCallback-1115-thread-1-processing-n:127.0.0.1:62200_solr)
[n:127.0.0.1:62200_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 625231 INFO (jetty-launcher-1096-thread-2)
[n:127.0.0.1:62201_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 625231 INFO (jetty-launcher-1096-thread-1)
[n:127.0.0.1:62200_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 625232 INFO (jetty-launcher-1096-thread-2)
[n:127.0.0.1:62201_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:62201_solr
[junit4] 2> 625233 INFO (jetty-launcher-1096-thread-1)
[n:127.0.0.1:62200_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:62200_solr
[junit4] 2> 625234 INFO (jetty-launcher-1096-thread-2)
[n:127.0.0.1:62201_solr ] o.a.s.c.Overseer Overseer
(id=72065502300340229-127.0.0.1:62201_solr-n_0000000000) starting
[junit4] 2> 625236 INFO
(zkCallback-1114-thread-1-processing-n:127.0.0.1:62200_solr)
[n:127.0.0.1:62200_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 625236 INFO
(zkCallback-1112-thread-1-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 625249 INFO (jetty-launcher-1096-thread-2)
[n:127.0.0.1:62201_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:62201_solr
[junit4] 2> 625251 INFO
(zkCallback-1114-thread-1-processing-n:127.0.0.1:62200_solr)
[n:127.0.0.1:62200_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 625252 INFO
(zkCallback-1112-thread-1-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 625306 INFO (jetty-launcher-1096-thread-1)
[n:127.0.0.1:62200_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62200.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6fc3bf12
[junit4] 2> 625338 INFO (jetty-launcher-1096-thread-1)
[n:127.0.0.1:62200_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62200.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6fc3bf12
[junit4] 2> 625338 INFO (jetty-launcher-1096-thread-1)
[n:127.0.0.1:62200_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62200.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6fc3bf12
[junit4] 2> 625340 INFO (jetty-launcher-1096-thread-1)
[n:127.0.0.1:62200_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_542CB894D9B201B6-001/tempDir-001/node1/.
[junit4] 2> 625355 INFO (jetty-launcher-1096-thread-2)
[n:127.0.0.1:62201_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62201.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6fc3bf12
[junit4] 2> 625382 INFO (jetty-launcher-1096-thread-2)
[n:127.0.0.1:62201_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62201.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6fc3bf12
[junit4] 2> 625382 INFO (jetty-launcher-1096-thread-2)
[n:127.0.0.1:62201_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62201.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6fc3bf12
[junit4] 2> 625383 INFO (jetty-launcher-1096-thread-2)
[n:127.0.0.1:62201_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_542CB894D9B201B6-001/tempDir-001/node2/.
[junit4] 2> 625405 INFO (zkConnectionManagerCallback-1121-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 625407 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x1000731492c0007, likely client has closed socket
[junit4] 2> 625410 INFO (zkConnectionManagerCallback-1125-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 625412 INFO
(SUITE-CloudExitableDirectoryReaderTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 625413 INFO
(SUITE-CloudExitableDirectoryReaderTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62198/solr ready
[junit4] 2> 625425 INFO (qtp336728945-5596) [n:127.0.0.1:62201_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
async=4e4398f4-742b-479b-9734-597571751c6e&replicationFactor=1&collection.configName=conf&name=exitable&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 625427 INFO (qtp336728945-5596) [n:127.0.0.1:62201_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=4e4398f4-742b-479b-9734-597571751c6e&replicationFactor=1&collection.configName=conf&name=exitable&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 625428 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=4e4398f4-742b-479b-9734-597571751c6e&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 625443 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=4e4398f4-742b-479b-9734-597571751c6e&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=14
[junit4] 2> 625443 INFO
(OverseerThreadFactory-2792-thread-1-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
exitable
[junit4] 2> 625562 INFO
(OverseerStateUpdate-72065502300340229-127.0.0.1:62201_solr-n_0000000000)
[n:127.0.0.1:62201_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"exitable",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"exitable_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:62200/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 625566 INFO
(OverseerStateUpdate-72065502300340229-127.0.0.1:62201_solr-n_0000000000)
[n:127.0.0.1:62201_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"exitable",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"exitable_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:62201/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 625775 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=exitable&version=2&replicaType=NRT&async=4e4398f4-742b-479b-9734-597571751c6e120671192697938&coreNodeName=core_node4&name=exitable_shard2_replica_n2&action=CREATE&numShards=2&shard=shard2&wt=javabin}
status=0 QTime=0
[junit4] 2> 625775 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&collection=exitable&version=2&replicaType=NRT&async=4e4398f4-742b-479b-9734-597571751c6e120671192697938&coreNodeName=core_node4&name=exitable_shard2_replica_n2&action=CREATE&numShards=2&shard=shard2&wt=javabin
[junit4] 2> 625775 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 625775 INFO (qtp1037145294-5594) [n:127.0.0.1:62200_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&collection=exitable&version=2&replicaType=NRT&async=4e4398f4-742b-479b-9734-597571751c6e120671188386020&coreNodeName=core_node3&name=exitable_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin}
status=0 QTime=0
[junit4] 2> 625775 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr ] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&collection=exitable&version=2&replicaType=NRT&async=4e4398f4-742b-479b-9734-597571751c6e120671188386020&coreNodeName=core_node3&name=exitable_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin
[junit4] 2> 625776 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 625776 INFO (qtp336728945-5603) [n:127.0.0.1:62201_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
4e4398f4-742b-479b-9734-597571751c6e120671192697938
[junit4] 2> 625776 INFO (qtp336728945-5603) [n:127.0.0.1:62201_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=4e4398f4-742b-479b-9734-597571751c6e120671192697938&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 625887 INFO
(zkCallback-1112-thread-1-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 625887 INFO
(zkCallback-1114-thread-1-processing-n:127.0.0.1:62200_solr)
[n:127.0.0.1:62200_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 625888 INFO
(zkCallback-1112-thread-2-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 625888 INFO
(zkCallback-1114-thread-2-processing-n:127.0.0.1:62200_solr)
[n:127.0.0.1:62200_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 626453 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=4e4398f4-742b-479b-9734-597571751c6e&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 626455 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=4e4398f4-742b-479b-9734-597571751c6e&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 626780 INFO (qtp336728945-5603) [n:127.0.0.1:62201_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
4e4398f4-742b-479b-9734-597571751c6e120671192697938
[junit4] 2> 626781 INFO (qtp336728945-5603) [n:127.0.0.1:62201_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=4e4398f4-742b-479b-9734-597571751c6e120671192697938&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 626798 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion:
8.0.0
[junit4] 2> 626798 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
8.0.0
[junit4] 2> 626806 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.s.IndexSchema [exitable_shard1_replica_n1]
Schema name=minimal
[junit4] 2> 626806 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.s.IndexSchema [exitable_shard2_replica_n2]
Schema name=minimal
[junit4] 2> 626808 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1
with uniqueid field id
[junit4] 2> 626808 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1
with uniqueid field id
[junit4] 2> 626808 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore
'exitable_shard1_replica_n1' using configuration from collection exitable,
trusted=true
[junit4] 2> 626808 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.CoreContainer Creating SolrCore
'exitable_shard2_replica_n2' using configuration from collection exitable,
trusted=true
[junit4] 2> 626809 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62201.solr.core.exitable.shard2.replica_n2' (registry
'solr.core.exitable.shard2.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6fc3bf12
[junit4] 2> 626809 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 626809 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.SolrCore [[exitable_shard2_replica_n2] ]
Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_542CB894D9B201B6-001/tempDir-001/node2/exitable_shard2_replica_n2],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_542CB894D9B201B6-001/tempDir-001/node2/./exitable_shard2_replica_n2/data/]
[junit4] 2> 626810 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_62200.solr.core.exitable.shard1.replica_n1' (registry
'solr.core.exitable.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@6fc3bf12
[junit4] 2> 626810 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 626810 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.SolrCore [[exitable_shard1_replica_n1] ]
Opening new SolrCore at
[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_542CB894D9B201B6-001/tempDir-001/node1/exitable_shard1_replica_n1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_542CB894D9B201B6-001/tempDir-001/node1/./exitable_shard1_replica_n1/data/]
[junit4] 2> 627046 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 627046 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 627048 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 627048 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 627054 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 627054 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 627055 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 627055 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 627070 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@f37245d[exitable_shard1_replica_n1] main]
[junit4] 2> 627072 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 627073 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 627073 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000ms.
[junit4] 2> 627074 INFO
(searcherExecutor-2797-thread-1-processing-x:exitable_shard1_replica_n1
r:core_node3 4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE
n:127.0.0.1:62200_solr s:shard1 c:exitable) [n:127.0.0.1:62200_solr c:exitable
s:shard1 r:core_node3 x:exitable_shard1_replica_n1] o.a.s.c.SolrCore
[exitable_shard1_replica_n1] Registered new searcher
Searcher@f37245d[exitable_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 627074 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in
index or recent updates, using new clock 1592485325264912384
[junit4] 2> 627081 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.s.SolrIndexSearcher Opening
[Searcher@6b6fd263[exitable_shard2_replica_n2] main]
[junit4] 2> 627084 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 627085 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 627086 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000ms.
[junit4] 2> 627086 INFO
(searcherExecutor-2798-thread-1-processing-x:exitable_shard2_replica_n2
r:core_node4 4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE
n:127.0.0.1:62201_solr s:shard2 c:exitable) [n:127.0.0.1:62201_solr c:exitable
s:shard2 r:core_node4 x:exitable_shard2_replica_n2] o.a.s.c.SolrCore
[exitable_shard2_replica_n2] Registered new searcher
Searcher@6b6fd263[exitable_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 627087 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.u.UpdateLog Could not find max version in
index or recent updates, using new clock 1592485325278543872
[junit4] 2> 627096 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 627096 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the
new leader - try and sync
[junit4] 2> 627096 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:62200/solr/exitable_shard1_replica_n1/
[junit4] 2> 627097 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 627097 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.SyncStrategy
http://127.0.0.1:62200/solr/exitable_shard1_replica_n1/ has no replicas
[junit4] 2> 627097 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 627099 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 627099 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I may be the
new leader - try and sync
[junit4] 2> 627099 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:62201/solr/exitable_shard2_replica_n2/
[junit4] 2> 627099 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 627099 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.SyncStrategy
http://127.0.0.1:62201/solr/exitable_shard2_replica_n2/ has no replicas
[junit4] 2> 627099 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext Found all
replicas participating in election, clear LIR
[junit4] 2> 627105 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new
leader: http://127.0.0.1:62200/solr/exitable_shard1_replica_n1/ shard1
[junit4] 2> 627106 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.ShardLeaderElectionContext I am the new
leader: http://127.0.0.1:62201/solr/exitable_shard2_replica_n2/ shard2
[junit4] 2> 627212 INFO
(zkCallback-1112-thread-2-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 627212 INFO
(zkCallback-1114-thread-1-processing-n:127.0.0.1:62200_solr)
[n:127.0.0.1:62200_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 627212 INFO
(zkCallback-1112-thread-1-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 627212 INFO
(zkCallback-1114-thread-2-processing-n:127.0.0.1:62200_solr)
[n:127.0.0.1:62200_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 627214 INFO
(parallelCoreAdminExecutor-2793-thread-1-processing-n:127.0.0.1:62200_solr
4e4398f4-742b-479b-9734-597571751c6e120671188386020 CREATE)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery
necessary
[junit4] 2> 627265 INFO
(parallelCoreAdminExecutor-2794-thread-1-processing-n:127.0.0.1:62201_solr
4e4398f4-742b-479b-9734-597571751c6e120671192697938 CREATE)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.ZkController I am the leader, no recovery
necessary
[junit4] 2> 627376 INFO
(zkCallback-1114-thread-2-processing-n:127.0.0.1:62200_solr)
[n:127.0.0.1:62200_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 627376 INFO
(zkCallback-1112-thread-1-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 627376 INFO
(zkCallback-1112-thread-2-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 627376 INFO
(zkCallback-1114-thread-1-processing-n:127.0.0.1:62200_solr)
[n:127.0.0.1:62200_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/exitable/state.json] for collection [exitable] has occurred -
updating... (live nodes size: [2])
[junit4] 2> 627465 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=4e4398f4-742b-479b-9734-597571751c6e&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 627466 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=4e4398f4-742b-479b-9734-597571751c6e&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 627786 INFO (qtp336728945-5603) [n:127.0.0.1:62201_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
4e4398f4-742b-479b-9734-597571751c6e120671192697938
[junit4] 2> 627786 INFO (qtp336728945-5603) [n:127.0.0.1:62201_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=4e4398f4-742b-479b-9734-597571751c6e120671192697938&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 627787 INFO (qtp1037145294-5597) [n:127.0.0.1:62200_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
4e4398f4-742b-479b-9734-597571751c6e120671188386020
[junit4] 2> 627787 INFO (qtp1037145294-5597) [n:127.0.0.1:62200_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=4e4398f4-742b-479b-9734-597571751c6e120671188386020&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 627791 INFO
(OverseerThreadFactory-2792-thread-1-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 628471 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=4e4398f4-742b-479b-9734-597571751c6e&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 628479 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=4e4398f4-742b-479b-9734-597571751c6e&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=8
[junit4] 2> 628480 INFO (qtp336728945-5603) [n:127.0.0.1:62201_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with
params
requestid=4e4398f4-742b-479b-9734-597571751c6e&action=DELETESTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 628487 INFO (qtp336728945-5603) [n:127.0.0.1:62201_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=4e4398f4-742b-479b-9734-597571751c6e&action=DELETESTATUS&wt=javabin&version=2}
status=0 QTime=6
[junit4] 2> 628531 INFO
(TEST-CloudExitableDirectoryReaderTest.test-seed#[542CB894D9B201B6]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 628565 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr
c:exitable s:shard2 r:core_node4 x:exitable_shard2_replica_n2]
o.a.s.u.p.LogUpdateProcessorFactory [exitable_shard2_replica_n2] webapp=/solr
path=/update params={_stateVer_=exitable:4&wt=javabin&version=2}{add=[2
(1592485326812610560), 3 (1592485326826242048), 5 (1592485326826242049), 6
(1592485326827290624), 7 (1592485326827290625), 9 (1592485326827290626), 17
(1592485326827290627), 18 (1592485326827290628), 19 (1592485326827290629), 21
(1592485326827290630), ... (24 adds)]} 0 15
[junit4] 2> 628567 INFO (qtp1037145294-5597) [n:127.0.0.1:62200_solr
c:exitable s:shard1 r:core_node3 x:exitable_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [exitable_shard1_replica_n1] webapp=/solr
path=/update params={_stateVer_=exitable:4&wt=javabin&version=2}{add=[1
(1592485326813659136), 4 (1592485326828339200), 8 (1592485326828339201), 10
(1592485326828339202), 11 (1592485326828339203), 12 (1592485326828339204), 13
(1592485326828339205), 14 (1592485326828339206), 15 (1592485326828339207), 16
(1592485326828339208), ... (33 adds)]} 0 15
[junit4] 2> 628594 INFO (qtp1037145294-5604) [n:127.0.0.1:62200_solr
c:exitable s:shard1 r:core_node3 x:exitable_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1592485326858747904,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 628594 INFO (qtp1037145294-5604) [n:127.0.0.1:62200_solr
c:exitable s:shard1 r:core_node3 x:exitable_shard1_replica_n1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@185dbfb5
commitCommandVersion:1592485326858747904
[junit4] 2> 628596 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr
c:exitable s:shard2 r:core_node4 x:exitable_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1592485326860845056,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 628597 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr
c:exitable s:shard2 r:core_node4 x:exitable_shard2_replica_n2]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@519636ea
commitCommandVersion:1592485326860845056
[junit4] 2> 628869 INFO (qtp1037145294-5604) [n:127.0.0.1:62200_solr
c:exitable s:shard1 r:core_node3 x:exitable_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@7f2c1742[exitable_shard1_replica_n1] main]
[junit4] 2> 628869 INFO (qtp1037145294-5604) [n:127.0.0.1:62200_solr
c:exitable s:shard1 r:core_node3 x:exitable_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 628874 INFO
(searcherExecutor-2797-thread-1-processing-n:127.0.0.1:62200_solr
x:exitable_shard1_replica_n1 s:shard1 c:exitable r:core_node3)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.SolrCore [exitable_shard1_replica_n1]
Registered new searcher Searcher@7f2c1742[exitable_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C33)))}
[junit4] 2> 628874 INFO (qtp1037145294-5604) [n:127.0.0.1:62200_solr
c:exitable s:shard1 r:core_node3 x:exitable_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [exitable_shard1_replica_n1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62200/solr/exitable_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 281
[junit4] 2> 628928 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr
c:exitable s:shard2 r:core_node4 x:exitable_shard2_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@78c436a2[exitable_shard2_replica_n2] main]
[junit4] 2> 628928 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr
c:exitable s:shard2 r:core_node4 x:exitable_shard2_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 628931 INFO
(searcherExecutor-2798-thread-1-processing-n:127.0.0.1:62201_solr
x:exitable_shard2_replica_n2 s:shard2 c:exitable r:core_node4)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.SolrCore [exitable_shard2_replica_n2]
Registered new searcher Searcher@78c436a2[exitable_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C24)))}
[junit4] 2> 628934 INFO (qtp336728945-5599) [n:127.0.0.1:62201_solr
c:exitable s:shard2 r:core_node4 x:exitable_shard2_replica_n2]
o.a.s.u.p.LogUpdateProcessorFactory [exitable_shard2_replica_n2] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:62200/solr/exitable_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 341
[junit4] 2> 628935 INFO (qtp1037145294-5602) [n:127.0.0.1:62200_solr
c:exitable s:shard1 r:core_node3 x:exitable_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [exitable_shard1_replica_n1] webapp=/solr
path=/update
params={_stateVer_=exitable:4&commit=true&wt=javabin&version=2}{commit=} 0 363
[junit4] 2> 628937 ERROR
(TEST-CloudExitableDirectoryReaderTest.test-seed#[542CB894D9B201B6]) [ ]
o.a.s.c.s.i.CloudSolrClient Request to collection [exitable] failed due to (0)
org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this
request exceeded, retry? 0
[junit4] 2> 628938 INFO
(TEST-CloudExitableDirectoryReaderTest.test-seed#[542CB894D9B201B6]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=CloudExitableDirectoryReaderTest -Dtests.method=test
-Dtests.seed=542CB894D9B201B6 -Dtests.slow=true -Dtests.locale=en-PN
-Dtests.timezone=America/Lima -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.45s J0 | CloudExitableDirectoryReaderTest.test <<<
[junit4] > Throwable #1:
org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this
request exceeded
[junit4] > at
__randomizedtesting.SeedInfo.seed([542CB894D9B201B6:DC78874E774E6C4E]:0)
[junit4] > at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1104)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884)
[junit4] > at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817)
[junit4] > at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
[junit4] > at
org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
[junit4] > at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.assertPartialResults(CloudExitableDirectoryReaderTest.java:106)
[junit4] > at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTimeoutTests(CloudExitableDirectoryReaderTest.java:78)
[junit4] > at
org.apache.solr.cloud.CloudExitableDirectoryReaderTest.test(CloudExitableDirectoryReaderTest.java:56)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 628959 INFO (jetty-closer-1097-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@374a2b70{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 628959 INFO (jetty-closer-1097-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@76b37be1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 628960 INFO (jetty-closer-1097-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1235038964
[junit4] 2> 628960 INFO (jetty-closer-1097-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1056179823
[junit4] 2> 628960 INFO (jetty-closer-1097-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 628960 INFO (jetty-closer-1097-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@779e1678: rootName =
solr_62201, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@1c4b8a02
[junit4] 2> 628960 INFO (jetty-closer-1097-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 628960 INFO (jetty-closer-1097-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@53435a35: rootName =
solr_62200, domain = solr.node, service url = null, agent id = null] for
registry solr.node / com.codahale.metrics.MetricRegistry@37acec81
[junit4] 2> 628971 INFO (jetty-closer-1097-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 628971 INFO (jetty-closer-1097-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5a7f017f: rootName =
solr_62201, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@4692eed2
[junit4] 2> 628975 INFO (jetty-closer-1097-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 628975 INFO (jetty-closer-1097-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1ddd2c90: rootName =
solr_62201, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@6aac85c
[junit4] 2> 628977 INFO (jetty-closer-1097-thread-2) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:62201_solr
[junit4] 2> 628977 INFO (jetty-closer-1097-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 628978 INFO (jetty-closer-1097-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@9602b4f: rootName =
solr_62200, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm / com.codahale.metrics.MetricRegistry@4692eed2
[junit4] 2> 628979 INFO (jetty-closer-1097-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 628980 INFO
(zkCallback-1114-thread-1-processing-n:127.0.0.1:62200_solr)
[n:127.0.0.1:62200_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 628980 INFO
(zkCallback-1112-thread-2-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 628980 INFO (coreCloseExecutor-2807-thread-1)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.c.SolrCore [exitable_shard2_replica_n2]
CLOSING SolrCore org.apache.solr.core.SolrCore@1f7fa2c
[junit4] 2> 628980 INFO (coreCloseExecutor-2807-thread-1)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.exitable.shard2.replica_n2, tag=33028652
[junit4] 2> 628980 INFO (coreCloseExecutor-2807-thread-1)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@9b613e1: rootName =
solr_62201, domain = solr.core.exitable.shard2.replica_n2, service url = null,
agent id = null] for registry solr.core.exitable.shard2.replica_n2 /
com.codahale.metrics.MetricRegistry@2fa8075f
[junit4] 2> 628982 INFO (jetty-closer-1097-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 628982 INFO (jetty-closer-1097-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1be6f8e8: rootName =
solr_62200, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty / com.codahale.metrics.MetricRegistry@6aac85c
[junit4] 2> 628984 INFO (jetty-closer-1097-thread-1) [ ]
o.a.s.c.ZkController Remove node as live in
ZooKeeper:/live_nodes/127.0.0.1:62200_solr
[junit4] 2> 628985 INFO (jetty-closer-1097-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 628986 INFO
(zkCallback-1112-thread-2-processing-n:127.0.0.1:62201_solr)
[n:127.0.0.1:62201_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 628986 INFO
(zkCallback-1114-thread-1-processing-n:127.0.0.1:62200_solr)
[n:127.0.0.1:62200_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (0)
[junit4] 2> 628986 INFO (coreCloseExecutor-2808-thread-1)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.c.SolrCore [exitable_shard1_replica_n1]
CLOSING SolrCore org.apache.solr.core.SolrCore@75b10781
[junit4] 2> 628986 INFO (coreCloseExecutor-2808-thread-1)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.exitable.shard1.replica_n1, tag=1974536065
[junit4] 2> 628986 INFO (coreCloseExecutor-2808-thread-1)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@1c8a3e3d: rootName =
solr_62200, domain = solr.core.exitable.shard1.replica_n1, service url = null,
agent id = null] for registry solr.core.exitable.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@3ac079d0
[junit4] 2> 628990 INFO (coreCloseExecutor-2807-thread-1)
[n:127.0.0.1:62201_solr c:exitable s:shard2 r:core_node4
x:exitable_shard2_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.exitable.shard2.leader, tag=33028652
[junit4] 2> 629000 INFO (coreCloseExecutor-2808-thread-1)
[n:127.0.0.1:62200_solr c:exitable s:shard1 r:core_node3
x:exitable_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.exitable.shard1.leader, tag=1974536065
[junit4] 2> 629022 INFO (jetty-closer-1097-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=72065502300340229-127.0.0.1:62201_solr-n_0000000000) closing
[junit4] 2> 629022 INFO
(OverseerStateUpdate-72065502300340229-127.0.0.1:62201_solr-n_0000000000)
[n:127.0.0.1:62201_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:62201_solr
[junit4] 2> 629022 WARN
(OverseerAutoScalingTriggerThread-72065502300340229-127.0.0.1:62201_solr-n_0000000000)
[n:127.0.0.1:62201_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 629026 INFO (jetty-closer-1097-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@500bc96{/solr,null,UNAVAILABLE}
[junit4] 2> 629027 INFO (jetty-closer-1097-thread-2) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 630536 INFO (jetty-closer-1097-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@46b3d206{/solr,null,UNAVAILABLE}
[junit4] 2> 630536 INFO (jetty-closer-1097-thread-1) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 630537 ERROR
(SUITE-CloudExitableDirectoryReaderTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 630538 INFO
(SUITE-CloudExitableDirectoryReaderTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:62198 62198
[junit4] 2> 630553 INFO (Thread-1267) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:62198 62198
[junit4] 2> 633050 WARN (Thread-1267) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/exitable/terms/shard2
[junit4] 2> 2 /solr/collections/exitable/terms/shard1
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 11 /solr/collections/exitable/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_542CB894D9B201B6-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{_version_=Lucene50(blocksize=128),
name=PostingsFormat(name=LuceneVarGapFixedInterval),
id=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{},
maxPointsInLeafNode=2046, maxMBSortInHeap=6.819715192306934,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@cf55552),
locale=en-PN, timezone=America/Lima
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9
(64-bit)/cpus=3,threads=1,free=63268384,total=423624704
[junit4] 2> NOTE: All tests run in this JVM: [ZkFailoverTest,
DistributedFacetPivotLargeTest, ClassificationUpdateProcessorTest,
TestClusterProperties, LeaderElectionIntegrationTest,
DirectSolrSpellCheckerTest, TestReload, SSLMigrationTest, QueryEqualityTest,
DateMathParserTest, TestApiFramework, QueryElevationComponentTest,
TestSolrConfigHandlerConcurrent, TestExpandComponent,
TestRandomCollapseQParserPlugin, DistributedExpandComponentTest, TestLazyCores,
TestLFUCache, TermsComponentTest, TestNamedUpdateProcessors, TestFieldCache,
AlternateDirectoryTest, TestSurroundQueryParser, TestInitParams,
MergeStrategyTest, TestSolrQueryResponse, TestTriggerIntegration,
MultiTermTest, NumericFieldsTest, DocValuesMissingTest,
DistributedMLTComponentTest, TestCollectionAPI, TestUnifiedSolrHighlighter,
TestStressUserVersions, ChaosMonkeyNothingIsSafeWithPullReplicasTest,
TestJsonFacets, TestRemoteStreaming, SolrMetricReporterTest,
AutoAddReplicasIntegrationTest, TestCloudInspectUtil, SuggesterFSTTest,
TolerantUpdateProcessorTest, ZkCLITest, TestSimpleTextCodec,
RAMDirectoryFactoryTest, TestSchemaSimilarityResource,
DistributedFacetPivotWhiteBoxTest, TestPKIAuthenticationPlugin,
BigEndianAscendingWordSerializerTest, TestRTimerTree, TestQueryTypes,
MoreLikeThisHandlerTest, TestReplicationHandler, TestSimpleQParserPlugin,
SuggesterTSTTest, CloudExitableDirectoryReaderTest]
[junit4] Completed [112/776 (1!)] on J0 in 8.19s, 1 test, 1 error <<<
FAILURES!
[...truncated 937 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> Creating dataDir:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_542CB894D9B201B6-001/init-core-data-001
[junit4] 2> 1817437 WARN
(SUITE-AutoscalingHistoryHandlerTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=11 numCloses=11
[junit4] 2> 1817437 INFO
(SUITE-AutoscalingHistoryHandlerTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1817438 INFO
(SUITE-AutoscalingHistoryHandlerTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0,
clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1817438 INFO
(SUITE-AutoscalingHistoryHandlerTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1817438 INFO
(SUITE-AutoscalingHistoryHandlerTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_542CB894D9B201B6-001/tempDir-001
[junit4] 2> 1817438 INFO
(SUITE-AutoscalingHistoryHandlerTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1817439 INFO (Thread-3660) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1817439 INFO (Thread-3660) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1817441 ERROR (Thread-3660) [ ] 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> 1817548 INFO
(SUITE-AutoscalingHistoryHandlerTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:50864
[junit4] 2> 1817556 INFO (zkConnectionManagerCallback-3690-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1817562 INFO (jetty-launcher-3687-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1817563 INFO (jetty-launcher-3687-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1817563 INFO (jetty-launcher-3687-thread-3) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1817565 INFO (jetty-launcher-3687-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1817565 INFO (jetty-launcher-3687-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1817565 INFO (jetty-launcher-3687-thread-1) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1817565 INFO (jetty-launcher-3687-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@b8055cd{/solr,null,AVAILABLE}
[junit4] 2> 1817566 INFO (jetty-launcher-3687-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@358bb53e{HTTP/1.1,[http/1.1]}{127.0.0.1:50866}
[junit4] 2> 1817566 INFO (jetty-launcher-3687-thread-1) [ ]
o.e.j.s.Server Started @1820489ms
[junit4] 2> 1817566 INFO (jetty-launcher-3687-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=50866}
[junit4] 2> 1817566 ERROR (jetty-launcher-3687-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1817568 INFO (jetty-launcher-3687-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 1817568 INFO (jetty-launcher-3687-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1817568 INFO (jetty-launcher-3687-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1817568 INFO (jetty-launcher-3687-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-15T16:52:24.453504Z
[junit4] 2> 1817570 INFO (jetty-launcher-3687-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1817570 INFO (jetty-launcher-3687-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1817570 INFO (jetty-launcher-3687-thread-2) [ ]
o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1817570 INFO (jetty-launcher-3687-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2e17e8ed{/solr,null,AVAILABLE}
[junit4] 2> 1817570 INFO (jetty-launcher-3687-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6c0a42ad{HTTP/1.1,[http/1.1]}{127.0.0.1:50867}
[junit4] 2> 1817570 INFO (jetty-launcher-3687-thread-2) [ ]
o.e.j.s.Server Started @1820494ms
[junit4] 2> 1817570 INFO (jetty-launcher-3687-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=50867}
[junit4] 2> 1817571 ERROR (jetty-launcher-3687-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1817571 INFO (jetty-launcher-3687-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 1817571 INFO (jetty-launcher-3687-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1817571 INFO (jetty-launcher-3687-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1817571 INFO (jetty-launcher-3687-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-15T16:52:24.456654Z
[junit4] 2> 1817573 INFO (jetty-launcher-3687-thread-3) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1817573 INFO (jetty-launcher-3687-thread-3) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1817573 INFO (jetty-launcher-3687-thread-3) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1817573 INFO (jetty-launcher-3687-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@fc9299b{/solr,null,AVAILABLE}
[junit4] 2> 1817573 INFO (jetty-launcher-3687-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@647cbf82{HTTP/1.1,[http/1.1]}{127.0.0.1:50868}
[junit4] 2> 1817574 INFO (jetty-launcher-3687-thread-3) [ ]
o.e.j.s.Server Started @1820497ms
[junit4] 2> 1817574 INFO (jetty-launcher-3687-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=50868}
[junit4] 2> 1817574 ERROR (jetty-launcher-3687-thread-3) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1817574 INFO (jetty-launcher-3687-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 1817575 INFO (jetty-launcher-3687-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1817575 INFO (jetty-launcher-3687-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1817575 INFO (jetty-launcher-3687-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-15T16:52:24.460120Z
[junit4] 2> 1817579 INFO (zkConnectionManagerCallback-3696-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1817580 INFO (zkConnectionManagerCallback-3692-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1817581 INFO (jetty-launcher-3687-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1817581 INFO (zkConnectionManagerCallback-3694-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1817582 INFO (jetty-launcher-3687-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1817592 INFO (jetty-launcher-3687-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1817593 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10007437b9c0002, likely client has closed socket
[junit4] 2> 1817595 INFO (jetty-launcher-3687-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50864/solr
[junit4] 2> 1817597 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10007437b9c0001, likely client has closed socket
[junit4] 2> 1817599 INFO (zkConnectionManagerCallback-3700-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1817599 INFO (jetty-launcher-3687-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50864/solr
[junit4] 2> 1817600 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10007437b9c0003, likely client has closed socket
[junit4] 2> 1817602 INFO (jetty-launcher-3687-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50864/solr
[junit4] 2> 1817602 INFO (zkConnectionManagerCallback-3704-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1817603 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10007437b9c0004, likely client has closed socket
[junit4] 2> 1817604 INFO (zkConnectionManagerCallback-3708-thread-1) [
] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1817604 INFO
(zkConnectionManagerCallback-3710-thread-1-processing-n:127.0.0.1:50868_solr)
[n:127.0.0.1:50868_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1817605 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10007437b9c0005, likely client has closed socket
[junit4] 2> 1817607 INFO
(zkConnectionManagerCallback-3712-thread-1-processing-n:127.0.0.1:50866_solr)
[n:127.0.0.1:50866_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1817607 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10007437b9c0006, likely client has closed socket
[junit4] 2> 1817608 INFO
(zkConnectionManagerCallback-3714-thread-1-processing-n:127.0.0.1:50867_solr)
[n:127.0.0.1:50867_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1817787 INFO (jetty-launcher-3687-thread-3)
[n:127.0.0.1:50868_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1817788 INFO (jetty-launcher-3687-thread-1)
[n:127.0.0.1:50866_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1817788 INFO (jetty-launcher-3687-thread-3)
[n:127.0.0.1:50868_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:50868_solr
[junit4] 2> 1817789 INFO (jetty-launcher-3687-thread-1)
[n:127.0.0.1:50866_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:50866_solr
[junit4] 2> 1817791 INFO (jetty-launcher-3687-thread-3)
[n:127.0.0.1:50868_solr ] o.a.s.c.Overseer Overseer
(id=72065580455952391-127.0.0.1:50868_solr-n_0000000000) starting
[junit4] 2> 1817792 INFO
(zkCallback-3709-thread-1-processing-n:127.0.0.1:50868_solr)
[n:127.0.0.1:50868_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1817792 INFO
(zkCallback-3711-thread-1-processing-n:127.0.0.1:50866_solr)
[n:127.0.0.1:50866_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1817799 INFO (jetty-launcher-3687-thread-2)
[n:127.0.0.1:50867_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1817801 DEBUG
(OverseerStateUpdate-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1817801 DEBUG
(OverseerAutoScalingTriggerThread-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.a.OverseerTriggerThread Adding
.autoAddReplicas trigger
[junit4] 2> 1817803 DEBUG
(OverseerAutoScalingTriggerThread-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing
/autoscaling.json with znode version 1
[junit4] 2> 1817803 DEBUG
(OverseerAutoScalingTriggerThread-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes:
[127.0.0.1:50866_solr]
[junit4] 2> 1817803 DEBUG
(OverseerAutoScalingTriggerThread-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 1817803 DEBUG
(OverseerAutoScalingTriggerThread-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger
updates upto znodeVersion 1
[junit4] 2> 1817803 INFO (jetty-launcher-3687-thread-3)
[n:127.0.0.1:50868_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:50868_solr
[junit4] 2> 1817805 DEBUG
(OverseerStateUpdate-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.Overseer processMessage: queueSize: 1,
message = {
[junit4] 2> "operation":"downnode",
[junit4] 2> "node_name":"127.0.0.1:50868_solr"} current state version: 0
[junit4] 2> 1817805 DEBUG
(OverseerStateUpdate-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for
node: 127.0.0.1:50868_solr
[junit4] 2> 1817811 INFO
(zkCallback-3711-thread-1-processing-n:127.0.0.1:50866_solr)
[n:127.0.0.1:50866_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1817812 INFO
(zkCallback-3709-thread-1-processing-n:127.0.0.1:50868_solr)
[n:127.0.0.1:50868_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1817812 INFO
(zkCallback-3713-thread-1-processing-n:127.0.0.1:50867_solr)
[n:127.0.0.1:50867_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1817817 INFO (jetty-launcher-3687-thread-2)
[n:127.0.0.1:50867_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1817820 DEBUG
(OverseerAutoScalingTriggerThread-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old
nodeAdded markers
[junit4] 2> 1817820 DEBUG
(OverseerAutoScalingTriggerThread-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.a.OverseerTriggerThread Current
znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1817820 DEBUG (ScheduledTrigger-8196-thread-1)
[n:127.0.0.1:50868_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger:
.auto_add_replicas with currently live nodes: 2
[junit4] 2> 1817822 INFO (jetty-launcher-3687
[...truncated too long message...]
porter [org.apache.solr.metrics.reporters.SolrJmxReporter@6716e50: rootName =
solr_50899, domain = solr.core..system.shard1.replica_n7, service url = null,
agent id = null] for registry solr.core..system.shard1.replica_n7 /
com.codahale.metrics.MetricRegistry@754af383
[junit4] 2> 1854504 INFO (jetty-closer-3688-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72065580455952391-127.0.0.1:50868_solr-n_0000000000) closing
[junit4] 2> 1854504 INFO
(OverseerStateUpdate-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:50868_solr
[junit4] 2> 1854506 WARN
(OverseerAutoScalingTriggerThread-72065580455952391-127.0.0.1:50868_solr-n_0000000000)
[n:127.0.0.1:50868_solr ] o.a.s.c.a.OverseerTriggerThread
OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1854508 DEBUG (jetty-closer-3688-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 1854511 INFO
(zkCallback-3713-thread-1-processing-n:127.0.0.1:50867_solr)
[n:127.0.0.1:50867_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:50867_solr
[junit4] 2> 1854512 INFO (jetty-closer-3688-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@fc9299b{/solr,null,UNAVAILABLE}
[junit4] 2> 1854522 INFO (coreCloseExecutor-8261-thread-2)
[n:127.0.0.1:50899_solr c:.system s:shard1 r:core_node8
x:.system_shard1_replica_n7] o.a.s.m.SolrMetricManager Closing metric reporters
for registry=solr.collection..system.shard1.leader, tag=2033809045
[junit4] 2> 1854523 INFO (jetty-closer-3688-thread-1) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 1854534 INFO (coreCloseExecutor-8262-thread-2)
[n:127.0.0.1:50867_solr c:.system s:shard1 r:core_node10
x:.system_shard1_replica_n9] o.a.s.m.SolrMetricManager Closing metric reporters
for registry=solr.collection..system.shard1.leader, tag=538652104
[junit4] 2> 1856098 INFO (jetty-closer-3688-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2e17e8ed{/solr,null,UNAVAILABLE}
[junit4] 2> 1856098 INFO (jetty-closer-3688-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7dd06d70{/solr,null,UNAVAILABLE}
[junit4] 2> 1856101 INFO (jetty-closer-3688-thread-2) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 1856101 INFO (jetty-closer-3688-thread-3) [ ]
o.e.j.s.session Stopped scavenging
[junit4] 2> 1856103 ERROR
(SUITE-AutoscalingHistoryHandlerTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1856104 INFO
(SUITE-AutoscalingHistoryHandlerTest-seed#[542CB894D9B201B6]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:50864 50864
[junit4] 2> 1856119 INFO (Thread-3660) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:50864 50864
[junit4] 2> 1856264 WARN (Thread-3660) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 23 /solr/collections/.system/terms/shard1
[junit4] 2> 17
/solr/collections/AutoscalingHistoryHandlerTest_collection/terms/shard1
[junit4] 2> 13 /solr/aliases.json
[junit4] 2> 13 /solr/clusterprops.json
[junit4] 2> 5 /solr/configs/.system
[junit4] 2> 5 /solr/configs/.system/managed-schema
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4
/solr/configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2> 4
/solr/configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 59 /solr/collections/.system/state.json
[junit4] 2> 40
/solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 13 /solr/clusterstate.json
[junit4] 2> 2
/solr/collections/AutoscalingHistoryHandlerTest_collection/leader_elect/shard1/election/72065580455952391-core_node6-n_0000000001
[junit4] 2> 2
/solr/collections/.system/leader_elect/shard1/election/72065580455952392-core_node5-n_0000000000
[junit4] 2> 2
/solr/collections/AutoscalingHistoryHandlerTest_collection/leader_elect/shard1/election/72065580455952393-core_node5-n_0000000000
[junit4] 2> 2
/solr/overseer_elect/election/72065580455952391-127.0.0.1:50868_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/live_nodes
[junit4] 2> 13 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_542CB894D9B201B6-001
[junit4] 2> Feb 15, 2018 4:53:03 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@378c0a0b),
locale=de-DE, timezone=Pacific/Guadalcanal
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 9
(64-bit)/cpus=3,threads=1,free=322436144,total=525860864
[junit4] 2> NOTE: All tests run in this JVM: [ZkFailoverTest,
DistributedFacetPivotLargeTest, ClassificationUpdateProcessorTest,
TestClusterProperties, LeaderElectionIntegrationTest,
DirectSolrSpellCheckerTest, TestReload, SSLMigrationTest, QueryEqualityTest,
DateMathParserTest, TestApiFramework, QueryElevationComponentTest,
TestSolrConfigHandlerConcurrent, TestExpandComponent,
TestRandomCollapseQParserPlugin, DistributedExpandComponentTest, TestLazyCores,
TestLFUCache, TermsComponentTest, TestNamedUpdateProcessors, TestFieldCache,
AlternateDirectoryTest, TestSurroundQueryParser, TestInitParams,
MergeStrategyTest, TestSolrQueryResponse, TestTriggerIntegration,
MultiTermTest, NumericFieldsTest, DocValuesMissingTest,
DistributedMLTComponentTest, TestCollectionAPI, TestUnifiedSolrHighlighter,
TestStressUserVersions, ChaosMonkeyNothingIsSafeWithPullReplicasTest,
TestJsonFacets, TestRemoteStreaming, SolrMetricReporterTest,
AutoAddReplicasIntegrationTest, TestCloudInspectUtil, SuggesterFSTTest,
TolerantUpdateProcessorTest, ZkCLITest, TestSimpleTextCodec,
RAMDirectoryFactoryTest, TestSchemaSimilarityResource,
DistributedFacetPivotWhiteBoxTest, TestPKIAuthenticationPlugin,
BigEndianAscendingWordSerializerTest, TestRTimerTree, TestQueryTypes,
MoreLikeThisHandlerTest, TestReplicationHandler, TestSimpleQParserPlugin,
SuggesterTSTTest, CloudExitableDirectoryReaderTest,
DirectUpdateHandlerOptimizeTest, TestTrie, TestSolr4Spatial2,
TestConfigSetsAPIExclusivity, CheckHdfsIndexTest, TestDistribStateManager,
DirectoryFactoryTest, RemoteQueryErrorTest, TestFiltering,
OpenCloseCoreStressTest, TestFaceting,
OverriddenZkACLAndCredentialsProvidersTest, TestPartialUpdateDeduplication,
CursorMarkTest, TestInPlaceUpdatesStandalone, MoveReplicaHDFSFailoverTest,
TestHighlightDedupGrouping, TestSkipOverseerOperations, CustomCollectionTest,
BlockCacheTest, TestSchemaNameResource, ConfigureRecoveryStrategyTest,
TestRangeQuery, TestExclusionRuleCollectionAccess,
TriLevelCompositeIdRoutingTest, SolrIndexConfigTest,
FieldMutatingUpdateProcessorTest, DistribJoinFromCollectionTest,
TestFieldSortValues, TermVectorComponentDistributedTest, ShardSplitTest,
TestJmxIntegration, TestCoreBackup, TestSolrDeletionPolicy1,
AnalyticsQueryTest, AnalysisAfterCoreReloadTest, TestGenericDistributedQueue,
SubstringBytesRefFilterTest, TestInitQParser, TestQuerySenderNoQuery,
TestBlendedInfixSuggestions, TestReplicaProperties, TestGraphMLResponseWriter,
TestDocBasedVersionConstraints, TestStressCloudBlindAtomicUpdates,
DistributedVersionInfoTest, SolrJmxReporterCloudTest, SolrTestCaseJ4Test,
TestSubQueryTransformer, SuggestComponentTest, TestPolicyCloud,
WordBreakSolrSpellCheckerTest, TestRandomFlRTGCloud, MigrateRouteKeyTest,
DocExpirationUpdateProcessorFactoryTest, SolrGangliaReporterTest,
JSONWriterTest, CollectionsAPIDistributedZkTest, ConnectionReuseTest,
TestBinaryField, TestMaxTokenLenTokenizer, TestConfigSetImmutable,
RequiredFieldsTest, TestMaxScoreQueryParser, SchemaApiFailureTest,
RecoveryZkTest, TestSubQueryTransformerDistrib, TestCollationFieldDocValues,
DistributedFacetSimpleRefinementLongTailTest, CdcrRequestHandlerTest,
TestNonDefinedSimilarityFactory, AddReplicaTest, TestNumericTerms64,
TestRawTransformer, TestNestedDocsSort, TestEmbeddedSolrServerSchemaAPI,
CdcrBidirectionalTest, TestReloadDeadlock, TestClusterStateProvider,
SynonymTokenizerTest, TestReloadAndDeleteDocs, TestDistribDocBasedVersion,
SpatialRPTFieldTypeTest, ConfigSetsAPITest, SolrIndexSplitterTest,
CoreAdminRequestStatusTest, TestShortCircuitedRequests, CdcrUpdateLogTest,
TestElisionMultitermQuery, HLLSerializationTest, TestManagedSchema, AssignTest,
TestSuggestSpellingConverter, FileUtilsTest, CurrencyFieldTypeTest,
PluginInfoTest, HdfsChaosMonkeySafeLeaderTest, NodeAddedTriggerTest,
ResponseHeaderTest, CollectionTooManyReplicasTest, CursorPagingTest,
TestNodeAddedTrigger, BooleanFieldTest,
TestOverriddenPrefixQueryForCustomFieldType, TestFieldCacheVsDocValues,
TestSSLRandomization, OutputWriterTest, TestReRankQParserPlugin,
StatsComponentTest, HttpPartitionOnCommitTest, BJQParserTest, SOLR749Test,
TestPolicyCloud, SpatialHeatmapFacetsTest, TestSystemIdResolver,
CacheHeaderTest, TestCustomStream, LeaderElectionTest, SolrPluginUtilsTest,
UniqFieldsUpdateProcessorFactoryTest, TestBlobHandler,
AutoscalingHistoryHandlerTest]
[junit4] Completed [378/776 (2!)] on J0 in 39.10s, 1 test, 1 failure <<<
FAILURES!
[...truncated 42451 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
