Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2044/
1 tests failed.
FAILED: org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates.test_dv_stored
Error Message:
Could not load collection from ZK: test_col
Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK:
test_col
at
__randomizedtesting.SeedInfo.seed([22151AF869EC1074:B0297B7CD5AB2DAC]:0)
at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1112)
at
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:646)
at
org.apache.solr.common.cloud.ClusterState.getCollectionOrNull(ClusterState.java:213)
at
org.apache.solr.common.cloud.ClusterState.getSlicesMap(ClusterState.java:153)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:154)
at
org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates.waitForRecoveriesToFinish(TestStressCloudBlindAtomicUpdates.java:461)
at
org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates.checkField(TestStressCloudBlindAtomicUpdates.java:306)
at
org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates.test_dv_stored(TestStressCloudBlindAtomicUpdates.java:205)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /collections/test_col/state.json
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:357)
at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:354)
at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:354)
at
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1124)
at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1110)
... 46 more
Build Log:
[...truncated 11182 lines...]
[junit4] Suite: org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/init-core-data-001
[junit4] 2> 713812 WARN
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=72 numCloses=72
[junit4] 2> 713812 INFO
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 713814 INFO
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=SSL overhead seems to cause
OutOfMemory when stress testing)
[junit4] 2> 713814 INFO
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 713815 INFO
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001
[junit4] 2> 713815 INFO
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 713815 INFO (Thread-650) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 713815 INFO (Thread-650) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 713888 ERROR (Thread-650) [ ] 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> 713918 INFO
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:43654
[junit4] 2> 713973 INFO (jetty-launcher-461-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 713974 INFO (jetty-launcher-461-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 713976 INFO (jetty-launcher-461-thread-4) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 713977 INFO (jetty-launcher-461-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@72c798a{/solr,null,AVAILABLE}
[junit4] 2> 713987 INFO (jetty-launcher-461-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 713987 INFO (jetty-launcher-461-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7c8a9850{/solr,null,AVAILABLE}
[junit4] 2> 713988 INFO (jetty-launcher-461-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6338c638{/solr,null,AVAILABLE}
[junit4] 2> 713989 INFO (jetty-launcher-461-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5b8a9ae9{/solr,null,AVAILABLE}
[junit4] 2> 714040 INFO (jetty-launcher-461-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6ede1697{HTTP/1.1,[http/1.1]}{127.0.0.1:44598}
[junit4] 2> 714040 INFO (jetty-launcher-461-thread-1) [ ]
o.e.j.s.Server Started @724854ms
[junit4] 2> 714040 INFO (jetty-launcher-461-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=44598}
[junit4] 2> 714040 ERROR (jetty-launcher-461-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 714040 INFO (jetty-launcher-461-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 714040 INFO (jetty-launcher-461-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 714040 INFO (jetty-launcher-461-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 714040 INFO (jetty-launcher-461-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-26T16:15:27.529Z
[junit4] 2> 714040 INFO (jetty-launcher-461-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4d82b77d{HTTP/1.1,[http/1.1]}{127.0.0.1:39684}
[junit4] 2> 714040 INFO (jetty-launcher-461-thread-3) [ ]
o.e.j.s.Server Started @724855ms
[junit4] 2> 714040 INFO (jetty-launcher-461-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39684}
[junit4] 2> 714040 ERROR (jetty-launcher-461-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-26T16:15:27.530Z
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1b4cbcf2{HTTP/1.1,[http/1.1]}{127.0.0.1:44566}
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-2) [ ]
o.e.j.s.Server Started @724855ms
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@69873aaf{HTTP/1.1,[http/1.1]}{127.0.0.1:41480}
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=44566}
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-4) [ ]
o.e.j.s.Server Started @724855ms
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=41480}
[junit4] 2> 714041 ERROR (jetty-launcher-461-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 714041 ERROR (jetty-launcher-461-thread-4) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-26T16:15:27.530Z
[junit4] 2> 714041 INFO (jetty-launcher-461-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 714042 INFO (jetty-launcher-461-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-26T16:15:27.531Z
[junit4] 2> 714081 INFO (jetty-launcher-461-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 714089 INFO (jetty-launcher-461-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 714097 INFO (jetty-launcher-461-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 714097 INFO (jetty-launcher-461-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 714130 INFO (jetty-launcher-461-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43654/solr
[junit4] 2> 714132 INFO (jetty-launcher-461-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43654/solr
[junit4] 2> 714198 INFO (jetty-launcher-461-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43654/solr
[junit4] 2> 714235 INFO (jetty-launcher-461-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43654/solr
[junit4] 2> 714412 INFO (jetty-launcher-461-thread-1)
[n:127.0.0.1:44598_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 714413 INFO (jetty-launcher-461-thread-1)
[n:127.0.0.1:44598_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:44598_solr
[junit4] 2> 714426 INFO (jetty-launcher-461-thread-1)
[n:127.0.0.1:44598_solr ] o.a.s.c.Overseer Overseer
(id=98375154227019786-127.0.0.1:44598_solr-n_0000000000) starting
[junit4] 2> 714507 INFO (jetty-launcher-461-thread-1)
[n:127.0.0.1:44598_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44598_solr
[junit4] 2> 714539 INFO
(zkCallback-481-thread-1-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 714579 INFO (jetty-launcher-461-thread-2)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 714658 INFO (jetty-launcher-461-thread-2)
[n:127.0.0.1:44566_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 714660 INFO (jetty-launcher-461-thread-2)
[n:127.0.0.1:44566_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44566_solr
[junit4] 2> 714661 INFO
(zkCallback-481-thread-1-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 714691 INFO
(zkCallback-483-thread-1-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 715082 INFO (jetty-launcher-461-thread-2)
[n:127.0.0.1:44566_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44566.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 715097 INFO (jetty-launcher-461-thread-2)
[n:127.0.0.1:44566_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44566.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 715097 INFO (jetty-launcher-461-thread-2)
[n:127.0.0.1:44566_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44566.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 715098 INFO (jetty-launcher-461-thread-2)
[n:127.0.0.1:44566_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node2/.
[junit4] 2> 715364 INFO (jetty-launcher-461-thread-1)
[n:127.0.0.1:44598_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44598.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 715367 INFO (jetty-launcher-461-thread-1)
[n:127.0.0.1:44598_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44598.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 715367 INFO (jetty-launcher-461-thread-1)
[n:127.0.0.1:44598_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44598.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 715368 INFO (jetty-launcher-461-thread-1)
[n:127.0.0.1:44598_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node1/.
[junit4] 2> 715501 INFO (jetty-launcher-461-thread-4)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 715511 INFO (jetty-launcher-461-thread-4)
[n:127.0.0.1:41480_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 715512 INFO (jetty-launcher-461-thread-4)
[n:127.0.0.1:41480_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:41480_solr
[junit4] 2> 715513 INFO
(zkCallback-483-thread-1-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 715529 INFO (jetty-launcher-461-thread-3)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 715530 INFO (jetty-launcher-461-thread-3)
[n:127.0.0.1:39684_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 715560 INFO (jetty-launcher-461-thread-3)
[n:127.0.0.1:39684_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39684_solr
[junit4] 2> 715613 INFO
(zkCallback-475-thread-1-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 715617 INFO
(zkCallback-483-thread-1-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 715630 INFO
(zkCallback-481-thread-2-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (4)
[junit4] 2> 715661 INFO
(zkCallback-482-thread-1-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (4)
[junit4] 2> 715943 INFO (jetty-launcher-461-thread-3)
[n:127.0.0.1:39684_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39684.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 715959 INFO (jetty-launcher-461-thread-3)
[n:127.0.0.1:39684_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39684.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 715959 INFO (jetty-launcher-461-thread-3)
[n:127.0.0.1:39684_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39684.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 715960 INFO (jetty-launcher-461-thread-3)
[n:127.0.0.1:39684_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node3/.
[junit4] 2> 716043 INFO (jetty-launcher-461-thread-4)
[n:127.0.0.1:41480_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41480.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 716058 INFO (jetty-launcher-461-thread-4)
[n:127.0.0.1:41480_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41480.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 716058 INFO (jetty-launcher-461-thread-4)
[n:127.0.0.1:41480_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41480.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 716059 INFO (jetty-launcher-461-thread-4)
[n:127.0.0.1:41480_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node4/.
[junit4] 2> 716198 INFO
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 716211 INFO
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43654/solr ready
[junit4] 2> 717478 INFO (qtp158264931-2719) [n:127.0.0.1:44598_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&property.config=solrconfig-tlog.xml&property.schema=schema-minimal-atomic-stress.xml&collection.configName=org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set&name=test_col&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 717524 INFO
(OverseerThreadFactory-1207-thread-1-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.CreateCollectionCmd Create collection
test_col
[junit4] 2> 717785 INFO
(OverseerStateUpdate-98375154227019786-127.0.0.1:44598_solr-n_0000000000)
[n:127.0.0.1:44598_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test_col",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"test_col_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:44566/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 717801 INFO
(OverseerStateUpdate-98375154227019786-127.0.0.1:44598_solr-n_0000000000)
[n:127.0.0.1:44598_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test_col",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"test_col_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:44598/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 717803 INFO
(OverseerStateUpdate-98375154227019786-127.0.0.1:44598_solr-n_0000000000)
[n:127.0.0.1:44598_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test_col",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"test_col_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:41480/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 717931 INFO
(OverseerStateUpdate-98375154227019786-127.0.0.1:44598_solr-n_0000000000)
[n:127.0.0.1:44598_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"test_col",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"test_col_shard2_replica_n7",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:39684/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 718223 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set&newCollection=true&collection=test_col&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-minimal-atomic-stress.xml&coreNodeName=core_node3&name=test_col_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin
[junit4] 2> 718224 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 718231 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set&newCollection=true&collection=test_col&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-minimal-atomic-stress.xml&coreNodeName=core_node5&name=test_col_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin
[junit4] 2> 718231 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 718365 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set&newCollection=true&collection=test_col&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-minimal-atomic-stress.xml&coreNodeName=core_node6&name=test_col_shard2_replica_n4&action=CREATE&numShards=2&shard=shard2&wt=javabin
[junit4] 2> 718365 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 718430 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set&newCollection=true&collection=test_col&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-minimal-atomic-stress.xml&coreNodeName=core_node8&name=test_col_shard2_replica_n7&action=CREATE&numShards=2&shard=shard2&wt=javabin
[junit4] 2> 718431 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 718735 INFO
(zkCallback-481-thread-1-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 718735 INFO
(zkCallback-481-thread-2-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 718739 INFO
(zkCallback-483-thread-1-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 718743 INFO
(zkCallback-482-thread-1-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 718743 INFO
(zkCallback-475-thread-1-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 718743 INFO
(zkCallback-483-thread-2-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 718743 INFO
(zkCallback-475-thread-2-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 718775 INFO
(zkCallback-482-thread-2-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 719494 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 719551 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 719713 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 719767 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.s.IndexSchema [test_col_shard1_replica_n2] Schema
name=minimal-atomic-stress
[junit4] 2> 719812 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.s.IndexSchema [test_col_shard2_replica_n4] Schema
name=minimal-atomic-stress
[junit4] 2> 719814 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.s.IndexSchema Loaded schema minimal-atomic-stress/1.6 with uniqueid field
id
[junit4] 2> 719814 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.CoreContainer Creating SolrCore 'test_col_shard2_replica_n4' using
configuration from collection test_col, trusted=true
[junit4] 2> 719814 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_41480.solr.core.test_col.shard2.replica_n4' (registry
'solr.core.test_col.shard2.replica_n4') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 719814 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 719814 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4] o.a.s.c.SolrCore
[[test_col_shard2_replica_n4] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node4/test_col_shard2_replica_n4],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node4/./test_col_shard2_replica_n4/data/]
[junit4] 2> 719830 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.s.IndexSchema Loaded schema minimal-atomic-stress/1.6 with uniqueid field
id
[junit4] 2> 719830 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.CoreContainer Creating SolrCore 'test_col_shard1_replica_n2' using
configuration from collection test_col, trusted=true
[junit4] 2> 719830 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44598.solr.core.test_col.shard1.replica_n2' (registry
'solr.core.test_col.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 719830 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 719830 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2] o.a.s.c.SolrCore
[[test_col_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node1/test_col_shard1_replica_n2],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node1/./test_col_shard1_replica_n2/data/]
[junit4] 2> 719845 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=35, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=40.623046875,
floorSegmentMB=0.421875, forceMergeDeletesPctAllowed=7.113739813263943,
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7357327832862031
[junit4] 2> 719845 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=35, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=40.623046875,
floorSegmentMB=0.421875, forceMergeDeletesPctAllowed=7.113739813263943,
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7357327832862031
[junit4] 2> 719870 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.s.IndexSchema [test_col_shard1_replica_n1] Schema
name=minimal-atomic-stress
[junit4] 2> 719870 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 719871 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.s.IndexSchema Loaded schema minimal-atomic-stress/1.6 with uniqueid field
id
[junit4] 2> 719871 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.c.CoreContainer Creating SolrCore 'test_col_shard1_replica_n1' using
configuration from collection test_col, trusted=true
[junit4] 2> 719872 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_44566.solr.core.test_col.shard1.replica_n1' (registry
'solr.core.test_col.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 719872 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 719872 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1] o.a.s.c.SolrCore
[[test_col_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node2/test_col_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node2/./test_col_shard1_replica_n1/data/]
[junit4] 2> 719901 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=35, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=40.623046875,
floorSegmentMB=0.421875, forceMergeDeletesPctAllowed=7.113739813263943,
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7357327832862031
[junit4] 2> 719968 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.s.IndexSchema [test_col_shard2_replica_n7] Schema
name=minimal-atomic-stress
[junit4] 2> 719969 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.s.IndexSchema Loaded schema minimal-atomic-stress/1.6 with uniqueid field
id
[junit4] 2> 719969 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.c.CoreContainer Creating SolrCore 'test_col_shard2_replica_n7' using
configuration from collection test_col, trusted=true
[junit4] 2> 719970 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39684.solr.core.test_col.shard2.replica_n7' (registry
'solr.core.test_col.shard2.replica_n7') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@77c60bbd
[junit4] 2> 719970 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 719970 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7] o.a.s.c.SolrCore
[[test_col_shard2_replica_n7] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node3/test_col_shard2_replica_n7],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001/tempDir-001/node3/./test_col_shard2_replica_n7/data/]
[junit4] 2> 719986 WARN (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 719989 WARN (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 720088 WARN (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 720139 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=35, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=40.623046875,
floorSegmentMB=0.421875, forceMergeDeletesPctAllowed=7.113739813263943,
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7357327832862031
[junit4] 2> 720218 WARN (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 720528 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 720529 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 720531 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 720531 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 720544 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 720544 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 720545 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 720545 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 720546 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=12, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=29.4150390625,
floorSegmentMB=1.66796875, forceMergeDeletesPctAllowed=13.12625436820934,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7649516854604783
[junit4] 2> 720546 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@59de0f3d[test_col_shard1_replica_n1] main]
[junit4] 2> 720547 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 720547 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 720547 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set
[junit4] 2> 720561 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=12, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=29.4150390625,
floorSegmentMB=1.66796875, forceMergeDeletesPctAllowed=13.12625436820934,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7649516854604783
[junit4] 2> 720561 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@79aef829[test_col_shard1_replica_n2] main]
[junit4] 2> 720562 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 720562 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 720562 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set
[junit4] 2> 720562 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set
[junit4] 2> 720563 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=12, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=29.4150390625,
floorSegmentMB=1.66796875, forceMergeDeletesPctAllowed=13.12625436820934,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7649516854604783
[junit4] 2> 720563 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 720578 INFO
(searcherExecutor-1217-thread-1-processing-n:127.0.0.1:44598_solr
x:test_col_shard1_replica_n2 s:shard1 c:test_col r:core_node5)
[n:127.0.0.1:44598_solr c:test_col s:shard1 r:core_node5
x:test_col_shard1_replica_n2] o.a.s.c.SolrCore [test_col_shard1_replica_n2]
Registered new searcher Searcher@79aef829[test_col_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 720578 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1574002474685038592
[junit4] 2> 720578 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set
[junit4] 2> 720579 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 720580 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 720580 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 720592 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1574002474699718656
[junit4] 2> 720610 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 720610 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 720611 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=12, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=29.4150390625,
floorSegmentMB=1.66796875, forceMergeDeletesPctAllowed=13.12625436820934,
segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.7649516854604783
[junit4] 2> 720612 INFO
(searcherExecutor-1218-thread-1-processing-n:127.0.0.1:44566_solr
x:test_col_shard1_replica_n1 s:shard1 c:test_col r:core_node3)
[n:127.0.0.1:44566_solr c:test_col s:shard1 r:core_node3
x:test_col_shard1_replica_n1] o.a.s.c.SolrCore [test_col_shard1_replica_n1]
Registered new searcher Searcher@59de0f3d[test_col_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 720626 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@7b153503[test_col_shard2_replica_n4] main]
[junit4] 2> 720627 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@7bcb8a0b[test_col_shard2_replica_n7] main]
[junit4] 2> 720642 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set
[junit4] 2> 720643 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set
[junit4] 2> 720643 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set
[junit4] 2> 720644 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 720645 INFO
(searcherExecutor-1216-thread-1-processing-n:127.0.0.1:41480_solr
x:test_col_shard2_replica_n4 s:shard2 c:test_col r:core_node6)
[n:127.0.0.1:41480_solr c:test_col s:shard2 r:core_node6
x:test_col_shard2_replica_n4] o.a.s.c.SolrCore [test_col_shard2_replica_n4]
Registered new searcher Searcher@7b153503[test_col_shard2_replica_n4]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 720648 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1574002474758438912
[junit4] 2> 720673 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 720674 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 720674 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:44598/solr/test_col_shard1_replica_n2/
[junit4] 2> 720674 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set
[junit4] 2> 720674 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2] o.a.s.u.PeerSync
PeerSync: core=test_col_shard1_replica_n2 url=http://127.0.0.1:44598/solr START
replicas=[http://127.0.0.1:44566/solr/test_col_shard1_replica_n1/] nUpdates=100
[junit4] 2> 720674 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 720676 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard2: total=2 found=1 timeoutin=9999ms
[junit4] 2> 720677 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2] o.a.s.u.PeerSync
PeerSync: core=test_col_shard1_replica_n2 url=http://127.0.0.1:44598/solr DONE.
We have no versions. sync failed.
[junit4] 2> 720677 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1574002474788847616
[junit4] 2> 720724 INFO
(searcherExecutor-1219-thread-1-processing-n:127.0.0.1:39684_solr
x:test_col_shard2_replica_n7 s:shard2 c:test_col r:core_node8)
[n:127.0.0.1:39684_solr c:test_col s:shard2 r:core_node8
x:test_col_shard2_replica_n7] o.a.s.c.SolrCore [test_col_shard2_replica_n7]
Registered new searcher Searcher@7bcb8a0b[test_col_shard2_replica_n7]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 720741 INFO (qtp890587982-2735) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.c.S.Request [test_col_shard1_replica_n1] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 720741 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 720741 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 720741 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 720774 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:44598/solr/test_col_shard1_replica_n2/ shard1
[junit4] 2> 720826 INFO
(zkCallback-481-thread-2-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 720828 INFO
(zkCallback-481-thread-1-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 720836 INFO
(zkCallback-483-thread-2-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 720836 INFO
(zkCallback-483-thread-1-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 720836 INFO
(zkCallback-475-thread-2-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 720837 INFO
(zkCallback-475-thread-1-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 720844 INFO
(zkCallback-482-thread-2-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 720845 INFO
(zkCallback-482-thread-1-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721052 INFO
(zkCallback-481-thread-2-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721056 INFO
(zkCallback-481-thread-1-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721056 INFO
(zkCallback-482-thread-1-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721056 INFO
(zkCallback-482-thread-2-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721060 INFO
(zkCallback-483-thread-2-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721060 INFO
(zkCallback-483-thread-1-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721060 INFO
(zkCallback-475-thread-1-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721060 INFO
(zkCallback-475-thread-2-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721094 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 721121 INFO (qtp158264931-2717) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set&newCollection=true&collection=test_col&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-minimal-atomic-stress.xml&coreNodeName=core_node5&name=test_col_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin}
status=0 QTime=2890
[junit4] 2> 721178 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 721178 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 721178 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:41480/solr/test_col_shard2_replica_n4/
[junit4] 2> 721179 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4] o.a.s.u.PeerSync
PeerSync: core=test_col_shard2_replica_n4 url=http://127.0.0.1:41480/solr START
replicas=[http://127.0.0.1:39684/solr/test_col_shard2_replica_n7/] nUpdates=100
[junit4] 2> 721179 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4] o.a.s.u.PeerSync
PeerSync: core=test_col_shard2_replica_n4 url=http://127.0.0.1:41480/solr DONE.
We have no versions. sync failed.
[junit4] 2> 721181 INFO (qtp76965383-2744) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.c.S.Request [test_col_shard2_replica_n7] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 721181 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the
next candidate
[junit4] 2> 721181 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 721182 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 721197 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:41480/solr/test_col_shard2_replica_n4/ shard2
[junit4] 2> 721381 INFO
(zkCallback-475-thread-2-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721381 INFO
(zkCallback-475-thread-1-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721381 INFO
(zkCallback-483-thread-1-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721381 INFO
(zkCallback-481-thread-1-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721381 INFO
(zkCallback-483-thread-2-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721381 INFO
(zkCallback-481-thread-2-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721397 INFO
(zkCallback-482-thread-2-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721397 INFO
(zkCallback-482-thread-1-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721476 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 721481 INFO (qtp1418184739-2729) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set&newCollection=true&collection=test_col&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-minimal-atomic-stress.xml&coreNodeName=core_node6&name=test_col_shard2_replica_n4&action=CREATE&numShards=2&shard=shard2&wt=javabin}
status=0 QTime=3116
[junit4] 2> 721650 INFO
(zkCallback-481-thread-2-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721650 INFO
(zkCallback-481-thread-1-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721654 INFO
(zkCallback-475-thread-1-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721654 INFO
(zkCallback-475-thread-2-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721654 INFO
(zkCallback-483-thread-2-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721658 INFO
(zkCallback-483-thread-1-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721670 INFO
(zkCallback-482-thread-1-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721670 INFO
(zkCallback-482-thread-2-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721764 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set&newCollection=true&collection=test_col&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-minimal-atomic-stress.xml&coreNodeName=core_node3&name=test_col_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin}
status=0 QTime=3540
[junit4] 2> 721782 INFO (qtp76965383-2746) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set&newCollection=true&collection=test_col&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-minimal-atomic-stress.xml&coreNodeName=core_node8&name=test_col_shard2_replica_n7&action=CREATE&numShards=2&shard=shard2&wt=javabin}
status=0 QTime=3351
[junit4] 2> 721797 INFO (qtp158264931-2719) [n:127.0.0.1:44598_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 721898 INFO
(zkCallback-482-thread-2-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721898 INFO
(zkCallback-475-thread-2-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721898 INFO
(zkCallback-482-thread-1-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721898 INFO
(zkCallback-475-thread-1-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721898 INFO
(zkCallback-483-thread-2-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721898 INFO
(zkCallback-481-thread-2-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721898 INFO
(zkCallback-481-thread-1-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 721906 INFO
(zkCallback-483-thread-1-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/test_col/state.json] for collection [test_col] has occurred -
updating... (live nodes size: [4])
[junit4] 2> 722803 INFO (qtp158264931-2719) [n:127.0.0.1:44598_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=2&property.config=solrconfig-tlog.xml&property.schema=schema-minimal-atomic-stress.xml&collection.configName=org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set&name=test_col&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2}
status=0 QTime=5325
[junit4] 2> 722804 INFO
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
test_col failOnTimeout:true timeout (sec):330
[junit4] 1> -
[junit4] 1> replica:core_node3 rstate:active live:true
[junit4] 1> replica:core_node5 rstate:active live:true
[junit4] 1> replica:core_node6 rstate:active live:true
[junit4] 1> replica:core_node8 rstate:active live:true
[junit4] 1> no one is recoverying
[junit4] 2> 722820 INFO
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: test_col
[junit4] 2> 722950 INFO (qtp158264931-2720) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.c.S.Request [test_col_shard1_replica_n2] webapp=/solr
path=/schema/fieldtypes/long params={wt=javabin&version=2} status=0 QTime=97
[junit4] 2> 723079 INFO
(TEST-TestStressCloudBlindAtomicUpdates.test_dv_stored-seed#[22151AF869EC1074])
[ ] o.a.s.SolrTestCaseJ4 ###Starting test_dv_stored
[junit4] 2> 723235 INFO (qtp76965383-2744) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard2_replica_n7] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&_version_=-1574002477355761664&distrib.from=http://127.0.0.1:41480/solr/test_col_shard2_replica_n4/&wt=javabin&version=2}{deleteByQuery=*:*
(-1574002477355761664)} 0 16
[junit4] 2> 723235 INFO (qtp1418184739-2727) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard2_replica_n4] webapp=/solr
path=/update
params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:44598/solr/test_col_shard1_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
(-1574002477355761664)} 0 115
[junit4] 2> 723258 INFO (qtp890587982-2739) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard1_replica_n1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&_version_=-1574002477323255808&distrib.from=http://127.0.0.1:44598/solr/test_col_shard1_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
(-1574002477323255808)} 0 133
[junit4] 2> 723258 INFO (qtp158264931-2720) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard1_replica_n2] webapp=/solr
path=/update params={wt=javabin&version=2}{deleteByQuery=*:*
(-1574002477323255808)} 0 179
[junit4] 2> 723300 INFO (qtp76965383-2749) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1574002477539262464,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 723300 WARN (qtp76965383-2749) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.DirectUpdateHandler2 Starting optimize... Reading and rewriting the
entire index! Use with care.
[junit4] 2> 723341 INFO (qtp890587982-2740) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1574002477581205504,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 723341 WARN (qtp890587982-2740) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 Starting optimize... Reading and rewriting the
entire index! Use with care.
[junit4] 2> 723341 INFO (qtp158264931-2721) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1574002477582254080,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 723341 WARN (qtp158264931-2721) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.DirectUpdateHandler2 Starting optimize... Reading and rewriting the
entire index! Use with care.
[junit4] 2> 723457 INFO (qtp890587982-2740) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 723457 INFO (qtp1418184739-2731) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1574002477703888896,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 723457 WARN (qtp1418184739-2731) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.DirectUpdateHandler2 Starting optimize... Reading and rewriting the
entire index! Use with care.
[junit4] 2> 723473 INFO (qtp158264931-2721) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 723539 INFO (qtp76965383-2749) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 723588 INFO (qtp1418184739-2731) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 723594 INFO (qtp158264931-2721) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 723595 INFO (qtp158264931-2721) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard1_replica_n2] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&optimize=true&maxSegments=1&waitSearcher=true&openSearcher=true&distrib.from=http://127.0.0.1:44598/solr/test_col_shard1_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{optimize=}
0 254
[junit4] 2> 723609 INFO (qtp890587982-2740) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 723609 INFO (qtp890587982-2740) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard1_replica_n1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&optimize=true&maxSegments=1&waitSearcher=true&openSearcher=true&distrib.from=http://127.0.0.1:44598/solr/test_col_shard1_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{optimize=}
0 268
[junit4] 2> 723609 INFO (qtp76965383-2749) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 723609 INFO (qtp76965383-2749) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard2_replica_n7] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&optimize=true&maxSegments=1&waitSearcher=true&openSearcher=true&distrib.from=http://127.0.0.1:44598/solr/test_col_shard1_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{optimize=}
0 309
[junit4] 2> 723610 INFO (qtp1418184739-2731) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 723636 INFO (qtp1418184739-2731) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard2_replica_n4] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&optimize=true&maxSegments=1&waitSearcher=true&openSearcher=true&distrib.from=http://127.0.0.1:44598/solr/test_col_shard1_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{optimize=}
0 178
[junit4] 2> 723637 INFO (qtp158264931-2723) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard1_replica_n2] webapp=/solr
path=/update
params={_stateVer_=test_col:7&optimize=true&maxSegments=1&waitSearcher=true&wt=javabin&version=2}{optimize=}
0 378
[junit4] 2> 723649 INFO (qtp890587982-2726) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.c.S.Request [test_col_shard1_replica_n1] webapp=/solr
path=/schema/fields/long_dv_stored params={wt=javabin&version=2} status=0
QTime=0
[junit4] 2> 723652 INFO
(TEST-TestStressCloudBlindAtomicUpdates.test_dv_stored-seed#[22151AF869EC1074])
[ ] o.a.s.c.TestStressCloudBlindAtomicUpdates Testing long_dv_stored:
numDocsToCheck=99, numDocsInIndex=495, incr=5
[junit4] 2> 723722 INFO (qtp890587982-2735) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard1_replica_n1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44598/solr/test_col_shard1_replica_n2/&wt=javabin&version=2}{add=[0
(1574002477959741440)]} 0 16
[junit4] 2> 723744 INFO (qtp158264931-2716) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard1_replica_n2] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[0 (1574002477959741440)]} 0 43
[junit4] 2> 723771 INFO
(OverseerCollectionConfigSetProcessor-98375154227019786-127.0.0.1:44598_solr-n_0000000000)
[n:127.0.0.1:44598_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> 723772 INFO (qtp890587982-2737) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard1_replica_n1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:44598/solr/test_col_shard1_replica_n2/&wt=javabin&version=2}{add=[1
(1574002478015315968)]} 0 15
[junit4] 2> 723772 INFO (qtp158264931-2719) [n:127.0.0.1:44598_solr
c:test_col s:shard1 r:core_node5 x:test_col_shard1_replica_n2]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard1_replica_n2] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[1 (1574002478015315968)]} 0 17
[junit4] 2> 723910 INFO (qtp76965383-2750) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard2_replica_n7] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41480/solr/test_col_shard2_replica_n4/&wt=javabin&version=2}{add=[2
(1574002478050967552)]} 0 115
[junit4] 2> 723940 INFO (qtp1418184739-2732) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard2_replica_n4] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[2 (1574002478050967552)]} 0 153
[junit4] 2> 723957 INFO (qtp76965383-2743) [n:127.0.0.1:39684_solr
c:test_col s:shard2 r:core_node8 x:test_col_shard2_replica_n7]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard2_replica_n7] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:41480/solr/test_col_shard2_replica_n4/&wt=javabin&version=2}{add=[3
(1574002478211399680)]} 0 15
[junit4] 2> 723958 INFO (qtp1418184739-2733) [n:127.0.0.1:41480_solr
c:test_col s:shard2 r:core_node6 x:test_col_shard2_replica_n4]
o.a.s.u.p.LogUpdateProcessorFactory [test_col_shard2_replica_n4] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[3 (1574002478211399680)]} 0 16
[junit4] 2> 723959 INFO (qtp890587982-2739) [n:127.0.0.1:44566_solr
c:test_col s:shard1 r:core_node3 x:test_col_shard1_replica_n1]
o.a.s.u.p.LogUpdateProcessorFactory [t
[...truncated too long message...]
r.collection.test_col.shard2.leader, tag=1223093358
[junit4] 2> 834198 INFO (coreCloseExecutor-1238-thread-1)
[n:127.0.0.1:44566_solr c:test_col s:shard1 r:core_node3
x:test_col_shard1_replica_n1] o.a.s.c.SolrCore [test_col_shard1_replica_n1]
CLOSING SolrCore org.apache.solr.core.SolrCore@72bb760e
[junit4] 2> 834198 INFO (coreCloseExecutor-1238-thread-1)
[n:127.0.0.1:44566_solr c:test_col s:shard1 r:core_node3
x:test_col_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 Committing on
IndexWriter close.
[junit4] 2> 834198 INFO (coreCloseExecutor-1238-thread-1)
[n:127.0.0.1:44566_solr c:test_col s:shard1 r:core_node3
x:test_col_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling setCommitData
with IW:org.apache.solr.update.SolrIndexWriter@6ea677dc commitCommandVersion:0
[junit4] 2> 834231 WARN
(zkCallback-482-thread-3-processing-n:127.0.0.1:41480_solr)
[n:127.0.0.1:41480_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 834231 INFO (jetty-closer-462-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 834307 INFO (coreCloseExecutor-1241-thread-1)
[n:127.0.0.1:44598_solr c:test_col s:shard1 r:core_node5
x:test_col_shard1_replica_n2] o.a.s.c.SolrCore [test_col_shard1_replica_n2]
CLOSING SolrCore org.apache.solr.core.SolrCore@56726963
[junit4] 2> 834307 INFO (coreCloseExecutor-1241-thread-1)
[n:127.0.0.1:44598_solr c:test_col s:shard1 r:core_node5
x:test_col_shard1_replica_n2] o.a.s.u.DirectUpdateHandler2 Committing on
IndexWriter close.
[junit4] 2> 834307 INFO (coreCloseExecutor-1241-thread-1)
[n:127.0.0.1:44598_solr c:test_col s:shard1 r:core_node5
x:test_col_shard1_replica_n2] o.a.s.u.SolrIndexWriter Calling setCommitData
with IW:org.apache.solr.update.SolrIndexWriter@53a2fe27 commitCommandVersion:0
[junit4] 2> 834308 INFO (coreCloseExecutor-1241-thread-1)
[n:127.0.0.1:44598_solr c:test_col s:shard1 r:core_node5
x:test_col_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.test_col.shard1.replica_n2, tag=1450338659
[junit4] 2> 834344 WARN
(zkCallback-475-thread-3-processing-n:127.0.0.1:39684_solr)
[n:127.0.0.1:39684_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 834482 INFO
(zkCallback-483-thread-4-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (2)
[junit4] 2> 834482 INFO (coreCloseExecutor-1238-thread-1)
[n:127.0.0.1:44566_solr c:test_col s:shard1 r:core_node3
x:test_col_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.core.test_col.shard1.replica_n1, tag=1924888078
[junit4] 2> 834486 INFO
(zkCallback-481-thread-6-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (2)
[junit4] 2> 834489 INFO (jetty-closer-462-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7c8a9850{/solr,null,UNAVAILABLE}
[junit4] 2> 834489 INFO (coreCloseExecutor-1241-thread-1)
[n:127.0.0.1:44598_solr c:test_col s:shard1 r:core_node5
x:test_col_shard1_replica_n2] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.test_col.shard1.leader, tag=1450338659
[junit4] 2> 834489 INFO (jetty-closer-462-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5b8a9ae9{/solr,null,UNAVAILABLE}
[junit4] 2> 834514 INFO (coreCloseExecutor-1238-thread-1)
[n:127.0.0.1:44566_solr c:test_col s:shard1 r:core_node3
x:test_col_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric
reporters for registry=solr.collection.test_col.shard1.leader, tag=1924888078
[junit4] 2> 834804 INFO
(zkCallback-481-thread-6-processing-n:127.0.0.1:44598_solr)
[n:127.0.0.1:44598_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 834846 INFO (jetty-closer-462-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=98375154227019786-127.0.0.1:44598_solr-n_0000000000) closing
[junit4] 2> 834846 INFO
(OverseerStateUpdate-98375154227019786-127.0.0.1:44598_solr-n_0000000000)
[n:127.0.0.1:44598_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:44598_solr
[junit4] 2> 835077 INFO (jetty-closer-462-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@72c798a{/solr,null,UNAVAILABLE}
[junit4] 2> 836311 WARN
(zkCallback-483-thread-4-processing-n:127.0.0.1:44566_solr)
[n:127.0.0.1:44566_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 836312 INFO (jetty-closer-462-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@6338c638{/solr,null,UNAVAILABLE}
[junit4] 2> 836328 ERROR
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-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> 836328 INFO
(SUITE-TestStressCloudBlindAtomicUpdates-seed#[22151AF869EC1074]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:43654 43654
[junit4] 2> 836609 INFO (Thread-650) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:43654 43654
[junit4] 2> 836609 WARN (Thread-650) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 4
/solr/configs/org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 40 /solr/collections/test_col/state.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_22151AF869EC1074-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{long_stored_idx=FSTOrd50, long_dv_idx=Lucene50(blocksize=128),
id=Lucene50(blocksize=128), long_dv_stored_idx=Lucene50(blocksize=128)},
docValues:{_version_=DocValuesFormat(name=Lucene70),
long_dv_idx=DocValuesFormat(name=Memory),
long_dv_stored=DocValuesFormat(name=Asserting),
id=DocValuesFormat(name=Memory), long_dv=DocValuesFormat(name=Asserting),
long_dv_stored_idx=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1803,
maxMBSortInHeap=5.735833939553219, sim=RandomSimilarity(queryNorm=false): {},
locale=he-IL, timezone=America/Puerto_Rico
[junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation
1.8.0_131 (64-bit)/cpus=4,threads=1,free=213571896,total=530579456
[junit4] 2> NOTE: All tests run in this JVM: [TestManagedSchemaAPI,
ZkCLITest, TestSimpleQParserPlugin, ResourceLoaderTest, TestCharFilters,
TestNestedDocsSort, TestSlowCompositeReaderWrapper,
TestCollationFieldDocValues, SuggestComponentTest,
DistributedIntervalFacetingTest, TestDistribDocBasedVersion,
TestSweetSpotSimilarityFactory, DeleteLastCustomShardedReplicaTest,
FacetPivotSmallTest, TestSimpleTrackingShardHandler, TestPerFieldSimilarity,
TestFieldCacheVsDocValues, ClusterStateUpdateTest, CreateCollectionCleanupTest,
TestBinaryResponseWriter, TestLegacyNumericRangeQueryBuilder,
TestMultiWordSynonyms, TestFiltering, DocValuesMultiTest,
TestCopyFieldCollectionResource, CollectionTooManyReplicasTest,
CoreAdminCreateDiscoverTest, BasicFunctionalityTest,
TestAuthenticationFramework, TestNumericTerms32, CSVRequestHandlerTest,
TestInitParams, SecurityConfHandlerTest, TestManagedResourceStorage,
TestDistributedGrouping, CdcrBootstrapTest, TestStressCloudBlindAtomicUpdates]
[junit4] Completed [119/726 (1!)] on J1 in 122.90s, 5 tests, 1 error <<<
FAILURES!
[...truncated 42024 lines...]
-ecj-javadoc-lint-src:
[mkdir] Created dir: /tmp/ecj5802582
[ecj-lint] Compiling 444 source files to /tmp/ecj5802582
[ecj-lint] ----------
[ecj-lint] 1. ERROR in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/solrj/src/java/org/apache/solr/client/solrj/SolrRequest.java
(at line 19)
[ecj-lint] import org.apache.solr.common.params.CommonParams;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.solr.common.params.CommonParams is never used
[ecj-lint] ----------
[ecj-lint] 1 problem (1 error)
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:810:
The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/build.xml:101:
The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build.xml:689:
The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/solrj/build.xml:67:
The following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/common-build.xml:2046:
Compile failed; see the compiler error output for details.
Total time: 166 minutes 27 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
