Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/798/
Java: 32bit/jdk1.8.0_121 -server -XX:+UseSerialGC
2 tests failed.
FAILED:
org.apache.solr.cloud.CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster
Error Message:
Document mismatch on target after sync expected:<2000> but was:<0>
Stack Trace:
java.lang.AssertionError: Document mismatch on target after sync
expected:<2000> but was:<0>
at
__randomizedtesting.SeedInfo.seed([96DFF943D4A29425:429AB21A33F427DE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at
org.apache.solr.cloud.CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster(CdcrBootstrapTest.java:309)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:745)
FAILED:
junit.framework.TestSuite.org.apache.solr.schema.TestUseDocValuesAsStored2
Error Message:
Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores\core:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores\core
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of
attempts):
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores\core:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores\core
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003
at __randomizedtesting.SeedInfo.seed([96DFF943D4A29425]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323)
at
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
at
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
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:745)
Build Log:
[...truncated 12507 lines...]
[junit4] Suite: org.apache.solr.cloud.CdcrBootstrapTest
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_96DFF943D4A29425-001\init-core-data-001
[junit4] 2> 1981275 WARN
(SUITE-CdcrBootstrapTest-seed#[96DFF943D4A29425]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 1981275 INFO
(SUITE-CdcrBootstrapTest-seed#[96DFF943D4A29425]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 1981280 INFO
(SUITE-CdcrBootstrapTest-seed#[96DFF943D4A29425]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1981282 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[96DFF943D4A29425])
[ ] o.a.s.SolrTestCaseJ4 ###Starting
testBootstrapWithContinousIndexingOnSourceCluster
[junit4] 2> 1981283 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[96DFF943D4A29425])
[ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_96DFF943D4A29425-001\cdcr-target-001
[junit4] 2> 1981283 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[96DFF943D4A29425])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1981283 INFO (Thread-4266) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1981283 INFO (Thread-4266) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1981383 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[96DFF943D4A29425])
[ ] o.a.s.c.ZkTestServer start zk server on port:49536
[junit4] 2> 1981392 INFO (jetty-launcher-2715-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1981393 INFO (jetty-launcher-2715-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@8e2647{/solr,null,AVAILABLE}
[junit4] 2> 1981394 INFO (jetty-launcher-2715-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@31369c{HTTP/1.1,[http/1.1]}{127.0.0.1:49540}
[junit4] 2> 1981394 INFO (jetty-launcher-2715-thread-1) [ ]
o.e.j.s.Server Started @1987227ms
[junit4] 2> 1981394 INFO (jetty-launcher-2715-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=49540}
[junit4] 2> 1981394 ERROR (jetty-launcher-2715-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1981394 INFO (jetty-launcher-2715-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.0
[junit4] 2> 1981394 INFO (jetty-launcher-2715-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1981394 INFO (jetty-launcher-2715-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1981394 INFO (jetty-launcher-2715-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-03-23T03:54:25.419Z
[junit4] 2> 1981398 INFO (jetty-launcher-2715-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1981414 INFO (jetty-launcher-2715-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1981415 INFO (jetty-launcher-2715-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49536/solr
[junit4] 2> 1981443 INFO (jetty-launcher-2715-thread-1)
[n:127.0.0.1:49540_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1981444 INFO (jetty-launcher-2715-thread-1)
[n:127.0.0.1:49540_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:49540_solr
[junit4] 2> 1981446 INFO (jetty-launcher-2715-thread-1)
[n:127.0.0.1:49540_solr ] o.a.s.c.Overseer Overseer
(id=97664451563552771-127.0.0.1:49540_solr-n_0000000000) starting
[junit4] 2> 1981455 INFO (jetty-launcher-2715-thread-1)
[n:127.0.0.1:49540_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:49540_solr
[junit4] 2> 1981457 INFO
(zkCallback-2722-thread-1-processing-n:127.0.0.1:49540_solr)
[n:127.0.0.1:49540_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1981590 INFO (jetty-launcher-2715-thread-1)
[n:127.0.0.1:49540_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_96DFF943D4A29425-001\cdcr-target-001\node1\.
[junit4] 1> Target zkHost = 127.0.0.1:49536/solr
[junit4] 2> 1981798 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15af94d1ea10005, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1981799 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[96DFF943D4A29425])
[ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_96DFF943D4A29425-001\cdcr-source-001
[junit4] 2> 1981799 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[96DFF943D4A29425])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1981799 INFO (Thread-4276) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1981800 INFO (Thread-4276) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1981804 WARN (Thread-4276) [ ] o.a.z.j.MBeanRegistry
Failed to register MBean StandaloneServer_port-1
[junit4] 2> 1981805 WARN (Thread-4276) [ ] o.a.z.s.ZooKeeperServer
Failed to register with JMX
[junit4] 2> javax.management.InstanceAlreadyExistsException:
org.apache.ZooKeeperService:name0=StandaloneServer_port-1
[junit4] 2> at
com.sun.jmx.mbeanserver.Repository.addMBean(Repository.java:437)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerWithRepository(DefaultMBeanServerInterceptor.java:1898)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerDynamicMBean(DefaultMBeanServerInterceptor.java:966)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerObject(DefaultMBeanServerInterceptor.java:900)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.registerMBean(DefaultMBeanServerInterceptor.java:324)
[junit4] 2> at
com.sun.jmx.mbeanserver.JmxMBeanServer.registerMBean(JmxMBeanServer.java:522)
[junit4] 2> at
org.apache.zookeeper.jmx.MBeanRegistry.register(MBeanRegistry.java:96)
[junit4] 2> at
org.apache.zookeeper.server.ZooKeeperServer.registerJMX(ZooKeeperServer.java:377)
[junit4] 2> at
org.apache.zookeeper.server.ZooKeeperServer.startup(ZooKeeperServer.java:410)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.startup(NIOServerCnxnFactory.java:123)
[junit4] 2> at
org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:308)
[junit4] 2> at
org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:490)
[junit4] 2> 1981900 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[96DFF943D4A29425])
[ ] o.a.s.c.ZkTestServer start zk server on port:49560
[junit4] 2> 1981911 INFO (jetty-launcher-2727-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1981913 INFO (jetty-launcher-2727-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@e573c1{/solr,null,AVAILABLE}
[junit4] 2> 1981914 INFO (jetty-launcher-2727-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1ba7910{HTTP/1.1,[http/1.1]}{127.0.0.1:49564}
[junit4] 2> 1981914 INFO (jetty-launcher-2727-thread-1) [ ]
o.e.j.s.Server Started @1987747ms
[junit4] 2> 1981914 INFO (jetty-launcher-2727-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=49564}
[junit4] 2> 1981914 ERROR (jetty-launcher-2727-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1981914 INFO (jetty-launcher-2727-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.0
[junit4] 2> 1981914 INFO (jetty-launcher-2727-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1981914 INFO (jetty-launcher-2727-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1981914 INFO (jetty-launcher-2727-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-03-23T03:54:25.939Z
[junit4] 2> 1981918 INFO (jetty-launcher-2727-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1981935 INFO (jetty-launcher-2727-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1981936 INFO (jetty-launcher-2727-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49560/solr
[junit4] 2> 1981967 INFO (jetty-launcher-2727-thread-1)
[n:127.0.0.1:49564_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1981968 INFO (jetty-launcher-2727-thread-1)
[n:127.0.0.1:49564_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:49564_solr
[junit4] 2> 1981969 INFO (jetty-launcher-2727-thread-1)
[n:127.0.0.1:49564_solr ] o.a.s.c.Overseer Overseer
(id=97664451597369347-127.0.0.1:49564_solr-n_0000000000) starting
[junit4] 2> 1981978 INFO (jetty-launcher-2727-thread-1)
[n:127.0.0.1:49564_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:49564_solr
[junit4] 2> 1981981 INFO
(zkCallback-2734-thread-1-processing-n:127.0.0.1:49564_solr)
[n:127.0.0.1:49564_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1982132 INFO (jetty-launcher-2727-thread-1)
[n:127.0.0.1:49564_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_96DFF943D4A29425-001\cdcr-source-001\node1\.
[junit4] 2> 1982417 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[96DFF943D4A29425])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1982418 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[96DFF943D4A29425])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:49560/solr ready
[junit4] 2> 1982426 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=cdcr-source&name=cdcr-source&action=CREATE&numShards=1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1982430 INFO
(OverseerThreadFactory-8109-thread-1-processing-n:127.0.0.1:49564_solr)
[n:127.0.0.1:49564_solr ] o.a.s.c.CreateCollectionCmd Create collection
cdcr-source
[junit4] 2> 1982538 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=cdcr-source&newCollection=true&name=cdcr-source_shard1_replica1&action=CREATE&numShards=1&collection=cdcr-source&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2
[junit4] 2> 1982647 INFO
(zkCallback-2734-thread-2-processing-n:127.0.0.1:49564_solr)
[n:127.0.0.1:49564_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/cdcr-source/state.json] for collection [cdcr-source] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1983569 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.6.0
[junit4] 2> 1983575 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but
managed schema resource managed-schema not found - loading non-managed schema
schema.xml instead
[junit4] 2> 1983580 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.s.IndexSchema
[cdcr-source_shard1_replica1] Schema name=minimal
[junit4] 2> 1983588 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1983589 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at
/configs/cdcr-source/managed-schema
[junit4] 2> 1983594 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in
ZooKeeper, renamed the non-managed schema /configs/cdcr-source/schema.xml to
/configs/cdcr-source/schema.xml.bak
[junit4] 2> 1983598 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.CoreContainer
Creating SolrCore 'cdcr-source_shard1_replica1' using configuration from
collection cdcr-source
[junit4] 2> 1983599 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1983599 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore
[[cdcr-source_shard1_replica1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_96DFF943D4A29425-001\cdcr-source-001\node1\cdcr-source_shard1_replica1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_96DFF943D4A29425-001\cdcr-source-001\node1\.\cdcr-source_shard1_replica1\data\]
[junit4] 2> 1983942 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 1983943 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1983944 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1983946 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1983952 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1d680d9[cdcr-source_shard1_replica1] main]
[junit4] 2> 1983954 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/cdcr-source
[junit4] 2> 1983955 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/cdcr-source
[junit4] 2> 1983955 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/cdcr-source/managed-schema
[junit4] 2> 1983955 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1983955 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1983961 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.h.CdcrBufferStateManager Created znode
/collections/cdcr-source/cdcr/state/buffer
[junit4] 2> 1983964 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.h.CdcrProcessStateManager Created znode
/collections/cdcr-source/cdcr/state/process
[junit4] 2> 1983977 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore
[cdcr-source_shard1_replica1] Registered new searcher
Searcher@1d680d9[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1983978 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1562631227837513728
[junit4] 2> 1983988 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1983988 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1983988 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:49564/solr/cdcr-source_shard1_replica1/
[junit4] 2> 1983988 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1983988 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SyncStrategy
http://127.0.0.1:49564/solr/cdcr-source_shard1_replica1/ has no replicas
[junit4] 2> 1983988 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1983993 INFO
(zkCallback-2734-thread-1-processing-n:127.0.0.1:49564_solr)
[n:127.0.0.1:49564_solr ] o.a.s.h.CdcrLeaderStateManager Received new leader
state @ cdcr-source:shard1
[junit4] 2> 1983994 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:49564/solr/cdcr-source_shard1_replica1/ shard1
[junit4] 2> 1984101 INFO
(zkCallback-2734-thread-1-processing-n:127.0.0.1:49564_solr)
[n:127.0.0.1:49564_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/cdcr-source/state.json] for collection [cdcr-source] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1984149 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 1984155 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=cdcr-source&newCollection=true&name=cdcr-source_shard1_replica1&action=CREATE&numShards=1&collection=cdcr-source&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2}
status=0 QTime=1617
[junit4] 2> 1984159 INFO (qtp786230-17270) [n:127.0.0.1:49564_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> 1984256 INFO
(zkCallback-2734-thread-3-processing-n:127.0.0.1:49564_solr)
[n:127.0.0.1:49564_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/cdcr-source/state.json] for collection [cdcr-source] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1984433 INFO
(OverseerCollectionConfigSetProcessor-97664451597369347-127.0.0.1:49564_solr-n_0000000000)
[n:127.0.0.1:49564_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> 1985160 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=cdcr-source&name=cdcr-source&action=CREATE&numShards=1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2}
status=0 QTime=2733
[junit4] 1> Adding 10 docs with commit=true, numDocs=100
[junit4] 2> 1985181 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=16
[junit4] 2> 1985183 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1985184 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1985217 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@318a47[cdcr-source_shard1_replica1]
main]
[junit4] 2> 1985218 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@318a47[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C100)))}
[junit4] 2> 1985218 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1985218 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=35
[junit4] 1> Adding 10 docs with commit=true, numDocs=200
[junit4] 2> 1985231 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 1985236 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1985236 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1985278 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@21c4c[cdcr-source_shard1_replica1]
main]
[junit4] 2> 1985279 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@21c4c[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C100)
Uninverting(_1(6.6.0):C100)))}
[junit4] 2> 1985279 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1985279 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=43
[junit4] 1> Adding 10 docs with commit=true, numDocs=300
[junit4] 2> 1985286 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1985289 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1985289 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1985323 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@10e046f[cdcr-source_shard1_replica1] main]
[junit4] 2> 1985324 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@10e046f[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C100)
Uninverting(_1(6.6.0):C100) Uninverting(_2(6.6.0):C100)))}
[junit4] 2> 1985324 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1985324 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=35
[junit4] 1> Adding 10 docs with commit=true, numDocs=400
[junit4] 2> 1985332 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1985334 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1985334 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1985371 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@68be3a[cdcr-source_shard1_replica1]
main]
[junit4] 2> 1985372 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@68be3a[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C100)
Uninverting(_1(6.6.0):C100) Uninverting(_2(6.6.0):C100)
Uninverting(_3(6.6.0):C100)))}
[junit4] 2> 1985372 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1985372 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=37
[junit4] 1> Adding 10 docs with commit=true, numDocs=500
[junit4] 2> 1985380 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1985382 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1985382 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1985426 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1847ecb[cdcr-source_shard1_replica1] main]
[junit4] 2> 1985426 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@1847ecb[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C100)
Uninverting(_1(6.6.0):C100) Uninverting(_2(6.6.0):C100)
Uninverting(_3(6.6.0):C100) Uninverting(_4(6.6.0):C100)))}
[junit4] 2> 1985427 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1985427 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=44
[junit4] 1> Adding 10 docs with commit=true, numDocs=600
[junit4] 2> 1985436 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 1985439 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1985439 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1985493 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@15bcee[cdcr-source_shard1_replica1]
main]
[junit4] 2> 1985493 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@15bcee[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C100)
Uninverting(_1(6.6.0):C100) Uninverting(_2(6.6.0):C100)
Uninverting(_3(6.6.0):C100) Uninverting(_4(6.6.0):C100)
Uninverting(_5(6.6.0):C100)))}
[junit4] 2> 1985494 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1985494 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=54
[junit4] 1> Adding 10 docs with commit=true, numDocs=700
[junit4] 2> 1985502 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1985503 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1985503 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1985549 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@bc2818[cdcr-source_shard1_replica1]
main]
[junit4] 2> 1985550 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@bc2818[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C100)
Uninverting(_1(6.6.0):C100) Uninverting(_2(6.6.0):C100)
Uninverting(_3(6.6.0):C100) Uninverting(_4(6.6.0):C100)
Uninverting(_5(6.6.0):C100) Uninverting(_6(6.6.0):C100)))}
[junit4] 2> 1985550 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1985550 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=47
[junit4] 1> Adding 10 docs with commit=true, numDocs=800
[junit4] 2> 1985557 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 1985559 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1985559 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1985610 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@ac8cdb[cdcr-source_shard1_replica1]
main]
[junit4] 2> 1985610 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@ac8cdb[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C100)
Uninverting(_1(6.6.0):C100) Uninverting(_2(6.6.0):C100)
Uninverting(_3(6.6.0):C100) Uninverting(_4(6.6.0):C100)
Uninverting(_5(6.6.0):C100) Uninverting(_6(6.6.0):C100)
Uninverting(_7(6.6.0):C100)))}
[junit4] 2> 1985611 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1985611 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=51
[junit4] 1> Adding 10 docs with commit=true, numDocs=900
[junit4] 2> 1985617 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1985620 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1985620 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1985679 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@13f1505[cdcr-source_shard1_replica1] main]
[junit4] 2> 1985680 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@13f1505[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C100)
Uninverting(_1(6.6.0):C100) Uninverting(_2(6.6.0):C100)
Uninverting(_3(6.6.0):C100) Uninverting(_4(6.6.0):C100)
Uninverting(_5(6.6.0):C100) Uninverting(_6(6.6.0):C100)
Uninverting(_7(6.6.0):C100) Uninverting(_8(6.6.0):C100)))}
[junit4] 2> 1985680 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1985680 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=60
[junit4] 1> Adding 10 docs with commit=true, numDocs=1000
[junit4] 2> 1985686 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 1985687 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1985687 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1985753 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@94eab0[cdcr-source_shard1_replica1]
main]
[junit4] 2> 1985774 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1985775 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@94eab0[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C100)
Uninverting(_1(6.6.0):C100) Uninverting(_2(6.6.0):C100)
Uninverting(_3(6.6.0):C100) Uninverting(_4(6.6.0):C100)
Uninverting(_5(6.6.0):C100) Uninverting(_6(6.6.0):C100)
Uninverting(_7(6.6.0):C100) Uninverting(_8(6.6.0):C100)
Uninverting(_9(6.6.0):C100)))}
[junit4] 2> 1985780 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=92
[junit4] 2> 1985782 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/select
params={q=*:*&_stateVer_=cdcr-source:3&wt=javabin&version=2} hits=1000 status=0
QTime=0
[junit4] 2> 1985798 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15af94d1ea10006, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1985802 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[96DFF943D4A29425])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1985803 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[96DFF943D4A29425])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:49536/solr ready
[junit4] 2> 1985810 INFO (qtp27869545-17227) [n:127.0.0.1:49540_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=cdcr-target&name=cdcr-target&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1985812 INFO
(OverseerThreadFactory-8101-thread-1-processing-n:127.0.0.1:49540_solr)
[n:127.0.0.1:49540_solr ] o.a.s.c.CreateCollectionCmd Create collection
cdcr-target
[junit4] 2> 1985919 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=cdcr-target&newCollection=true&name=cdcr-target_shard1_replica1&action=CREATE&numShards=1&collection=cdcr-target&shard=shard1&wt=javabin&version=2
[junit4] 2> 1986028 INFO
(zkCallback-2722-thread-3-processing-n:127.0.0.1:49540_solr)
[n:127.0.0.1:49540_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/cdcr-target/state.json] for collection [cdcr-target] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1986952 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.6.0
[junit4] 2> 1986956 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but
managed schema resource managed-schema not found - loading non-managed schema
schema.xml instead
[junit4] 2> 1986962 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.s.IndexSchema
[cdcr-target_shard1_replica1] Schema name=minimal
[junit4] 2> 1986968 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1986969 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at
/configs/cdcr-target/managed-schema
[junit4] 2> 1986974 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in
ZooKeeper, renamed the non-managed schema /configs/cdcr-target/schema.xml to
/configs/cdcr-target/schema.xml.bak
[junit4] 2> 1986980 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.CoreContainer
Creating SolrCore 'cdcr-target_shard1_replica1' using configuration from
collection cdcr-target
[junit4] 2> 1986980 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 1986981 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SolrCore
[[cdcr-target_shard1_replica1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_96DFF943D4A29425-001\cdcr-target-001\node1\cdcr-target_shard1_replica1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.CdcrBootstrapTest_96DFF943D4A29425-001\cdcr-target-001\node1\.\cdcr-target_shard1_replica1\data\]
[junit4] 2> 1987151 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 1987152 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1987156 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1987156 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1987157 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@e0c36e[cdcr-target_shard1_replica1]
main]
[junit4] 2> 1987159 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/cdcr-target
[junit4] 2> 1987160 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/cdcr-target
[junit4] 2> 1987160 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at
/configs/cdcr-target/managed-schema
[junit4] 2> 1987161 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1987161 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1987165 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.h.CdcrBufferStateManager Created znode
/collections/cdcr-target/cdcr/state/buffer
[junit4] 2> 1987168 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.h.CdcrProcessStateManager Created znode
/collections/cdcr-target/cdcr/state/process
[junit4] 2> 1987170 INFO
(searcherExecutor-8118-thread-1-processing-n:127.0.0.1:49540_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SolrCore
[cdcr-target_shard1_replica1] Registered new searcher
Searcher@e0c36e[cdcr-target_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1987171 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1562631231185616896
[junit4] 2> 1987181 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1987181 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1987181 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:49540/solr/cdcr-target_shard1_replica1/
[junit4] 2> 1987181 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1987181 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SyncStrategy
http://127.0.0.1:49540/solr/cdcr-target_shard1_replica1/ has no replicas
[junit4] 2> 1987181 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 1987186 INFO
(zkCallback-2722-thread-2-processing-n:127.0.0.1:49540_solr)
[n:127.0.0.1:49540_solr ] o.a.s.h.CdcrLeaderStateManager Received new leader
state @ cdcr-target:shard1
[junit4] 2> 1987191 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:49540/solr/cdcr-target_shard1_replica1/ shard1
[junit4] 2> 1987297 INFO
(zkCallback-2722-thread-4-processing-n:127.0.0.1:49540_solr)
[n:127.0.0.1:49540_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/cdcr-target/state.json] for collection [cdcr-target] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1987345 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 1987352 INFO (qtp27869545-17229) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=cdcr-target&newCollection=true&name=cdcr-target_shard1_replica1&action=CREATE&numShards=1&collection=cdcr-target&shard=shard1&wt=javabin&version=2}
status=0 QTime=1432
[junit4] 2> 1987355 INFO (qtp27869545-17227) [n:127.0.0.1:49540_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> 1987454 INFO
(zkCallback-2722-thread-3-processing-n:127.0.0.1:49540_solr)
[n:127.0.0.1:49540_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/cdcr-target/state.json] for collection [cdcr-target] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 1987816 INFO
(OverseerCollectionConfigSetProcessor-97664451563552771-127.0.0.1:49540_solr-n_0000000000)
[n:127.0.0.1:49540_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> 1988356 INFO (qtp27869545-17227) [n:127.0.0.1:49540_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=cdcr-target&name=cdcr-target&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=2546
[junit4] 2> 1989362 INFO
(zkCallback-2722-thread-3-processing-n:127.0.0.1:49540_solr)
[n:127.0.0.1:49540_solr ] o.a.s.h.CdcrProcessStateManager The CDCR process
state has changed: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/cdcr-target/cdcr/state/process @ cdcr-target:shard1
[junit4] 2> 1989363 INFO (qtp27869545-17230) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.S.Request [cdcr-target_shard1_replica1] webapp=/solr path=/cdcr
params={qt=/cdcr&_stateVer_=cdcr-target:3&action=start&wt=javabin&version=2}
status=0 QTime=4
[junit4] 2> 1989364 INFO
(zkCallback-2722-thread-3-processing-n:127.0.0.1:49540_solr)
[n:127.0.0.1:49540_solr ] o.a.s.h.CdcrProcessStateManager Received new CDCR
process state from watcher: STARTED @ cdcr-target:shard1
[junit4] 2> 1989371 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1989375 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49536/solr ready
[junit4] 2> 1989397 INFO (qtp27869545-17228) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.h.CdcrRequestHandler Found maxVersionFromRecent 0 maxVersionFromIndex 0
[junit4] 2> 1989397 INFO (qtp27869545-17228) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.S.Request [cdcr-target_shard1_replica1] webapp=/solr path=/cdcr
params={action=SHARDCHECKPOINT&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1989401 INFO (qtp27869545-17225) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.S.Request [cdcr-target_shard1_replica1] webapp=/solr path=/cdcr
params={_stateVer_=cdcr-target:3&action=COLLECTIONCHECKPOINT&wt=javabin&version=2}
status=0 QTime=14
[junit4] 2> 1989401 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.h.CdcrReplicatorManager Create new update log reader for target
cdcr-target with checkpoint -1 @ cdcr-source:shard1
[junit4] 2> 1989401 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.h.CdcrReplicatorManager Attempting to bootstrap target collection:
cdcr-target, shard: shard1
[junit4] 2> 1989402 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.h.CdcrReplicatorManager Submitting bootstrap task to executor
[junit4] 2> 1989404 INFO
(zkCallback-2734-thread-3-processing-n:127.0.0.1:49564_solr)
[n:127.0.0.1:49564_solr ] o.a.s.h.CdcrProcessStateManager The CDCR process
state has changed: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/cdcr-source/cdcr/state/process @ cdcr-source:shard1
[junit4] 2> 1989406 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/cdcr
params={qt=/cdcr&_stateVer_=cdcr-source:3&action=start&wt=javabin&version=2}
status=0 QTime=41
[junit4] 1> Adding 10 docs with commit=true, numDocs=1100
[junit4] 2> 1989408 INFO
(zkCallback-2734-thread-3-processing-n:127.0.0.1:49564_solr)
[n:127.0.0.1:49564_solr ] o.a.s.h.CdcrProcessStateManager Received new CDCR
process state from watcher: STARTED @ cdcr-source:shard1
[junit4] 2> 1989415 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 1989416 INFO
(cdcr-bootstrap-status-2746-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.h.CdcrReplicatorManager Attempting to
bootstrap target collection: cdcr-target shard: shard1 leader:
http://127.0.0.1:49540/solr/cdcr-target_shard1_replica1/
[junit4] 2> 1989418 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1989418 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1989422 INFO (qtp27869545-17228) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.S.Request [cdcr-target_shard1_replica1] webapp=/solr path=/cdcr
params={qt=/cdcr&masterUrl=http://127.0.0.1:49564/solr/cdcr-source_shard1_replica1/&action=BOOTSTRAP&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 1989435 INFO (qtp27869545-17228) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.S.Request [cdcr-target_shard1_replica1] webapp=/solr path=/cdcr
params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1989435 WARN
(cdcr-bootstrap-status-2746-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.h.CdcrReplicatorManager Bootstrap process
was not found on target collection: cdcr-target shard: shard1, leader:
http://127.0.0.1:49540/solr/cdcr-target_shard1_replica1/
[junit4] 2> 1989445 INFO
(cdcr-bootstrap-status-2746-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.h.CdcrReplicatorManager Attempting to
bootstrap target collection: cdcr-target shard: shard1 leader:
http://127.0.0.1:49540/solr/cdcr-target_shard1_replica1/
[junit4] 2> 1989448 INFO (qtp27869545-17228) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.S.Request [cdcr-target_shard1_replica1] webapp=/solr path=/cdcr
params={qt=/cdcr&masterUrl=http://127.0.0.1:49564/solr/cdcr-source_shard1_replica1/&action=BOOTSTRAP&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1989469 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@167a67e[cdcr-source_shard1_replica1] main]
[junit4] 2> 1989470 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1989472 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@167a67e[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.6.0):C1000)
Uninverting(_b(6.6.0):C100)))}
[junit4] 2> 1989557 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=139
[junit4] 1> Adding 10 docs with commit=true, numDocs=1200
[junit4] 2> 1989567 INFO (qtp27869545-17228) [n:127.0.0.1:49540_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.S.Request [cdcr-target_shard1_replica1] webapp=/solr path=/cdcr
params={qt=/cdcr&action=BOOTSTRAP_STATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1989567 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 1989568 INFO
(cdcr-bootstrap-status-2746-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.h.CdcrReplicatorManager CDCR bootstrap
running for 1 seconds, sleeping for 2000 ms
[junit4] 2> 1989569 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1989569 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1989599 INFO
(recoveryExecutor-2720-thread-1-processing-n:127.0.0.1:49540_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:49540_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1989622 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@13738da[cdcr-source_shard1_replica1] main]
[junit4] 2> 1989623 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1989628 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@13738da[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.6.0):C1000)
Uninverting(_b(6.6.0):C100) Uninverting(_c(6.6.0):C100)))}
[junit4] 2> 1989628 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=59
[junit4] 1> Adding 10 docs with commit=true, numDocs=1300
[junit4] 2> 1989635 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 1989637 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1989637 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1989674 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1459ce2[cdcr-source_shard1_replica1] main]
[junit4] 2> 1989675 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1989680 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@1459ce2[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.6.0):C1000)
Uninverting(_b(6.6.0):C100) Uninverting(_c(6.6.0):C100)
Uninverting(_d(6.6.0):C100)))}
[junit4] 2> 1989680 INFO (qtp786230-17268) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=43
[junit4] 1> Adding 10 docs with commit=true, numDocs=1400
[junit4] 2> 1989681 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1989682 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1989682 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1989682 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={waitSearcher=true&openSearcher=false&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1989688 INFO (qtp786230-17270) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1989690 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1989690 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1989728 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@17e6960[cdcr-source_shard1_replica1] main]
[junit4] 2> 1989729 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1989734 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@17e6960[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.6.0):C1000)
Uninverting(_b(6.6.0):C100) Uninverting(_c(6.6.0):C100)
Uninverting(_d(6.6.0):C100) Uninverting(_e(6.6.0):C100)))}
[junit4] 2> 1989734 INFO (qtp786230-17272) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=44
[junit4] 1> Adding 10 docs with commit=true, numDocs=1500
[junit4] 2> 1989741 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 1989742 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1989742 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1989787 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@d9a162[cdcr-source_shard1_replica1]
main]
[junit4] 2> 1989788 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1989791 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@d9a162[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.6.0):C1000)
Uninverting(_b(6.6.0):C100) Uninverting(_c(6.6.0):C100)
Uninverting(_d(6.6.0):C100) Uninverting(_e(6.6.0):C100)
Uninverting(_f(6.6.0):C100)))}
[junit4] 2> 1989791 INFO (qtp786230-17269) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=49
[junit4] 1> Adding 10 docs with commit=true, numDocs=1600
[junit4] 2> 1989800 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 1989802 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1989802 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1989823 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/replication
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0
QTime=0
[junit4] 2> 1989841 INFO
(recoveryExecutor-2720-thread-1-processing-n:127.0.0.1:49540_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:49540_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Master's generation: 16
[junit4] 2> 1989846 INFO
(recoveryExecutor-2720-thread-1-processing-n:127.0.0.1:49540_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:49540_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Master's version:
1490241273767
[junit4] 2> 1989846 INFO
(recoveryExecutor-2720-thread-1-processing-n:127.0.0.1:49540_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:49540_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1989846 INFO
(recoveryExecutor-2720-thread-1-processing-n:127.0.0.1:49540_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:49540_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Slave's version: 0
[junit4] 2> 1989846 INFO
(recoveryExecutor-2720-thread-1-processing-n:127.0.0.1:49540_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:49540_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1989861 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1851b62[cdcr-source_shard1_replica1] main]
[junit4] 2> 1989862 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1989866 INFO
(searcherExecutor-8112-thread-1-processing-n:127.0.0.1:49564_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:49564_solr c:cdcr-source s:shard1 r:core_node1
x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore [cdcr-source_shard1_replica1]
Registered new searcher Searcher@1851b62[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.6.0):C1000)
Uninverting(_b(6.6.0):C100) Uninverting(_c(6.6.0):C100)
Uninverting(_d(6.6.0):C100) Uninverting(_e(6.6.0):C100)
Uninverting(_f(6.6.0):C100) Uninverting(_g(6.6.0):C100)))}
[junit4] 2> 1989866 INFO (qtp786230-17271) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=64
[junit4] 1> Adding 10 docs with commit=true, numDocs=1700
[junit4] 2> 1989873 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.c.S.Request [cdcr-source_shard1_replica1] webapp=/solr path=/update
params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 1989876 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1989876 INFO (qtp786230-17266) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@deb463
[junit4] 2> 1989880 INFO (qtp786230-17273) [n:127.0.0.1:49564_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.h.ReplicationHandler Adding tlog files to list: [{size=4649,
name=tlog.0000000000000000000.1562631229082173440}, {size=4770,
name=tlog.0000000000000000001.1562631229140893696}, {size=4770,
name=tlog.0000000000000000002.1562631229203808256}, {size=4770,
name=tlog.0000000000000000003.1562631229252042752}, {size=4770,
name=tlog.0000000000000000004.1562631229302374400}, {size=4770,
name=tlog.0000000000000000005.1562631229358997504}, {size=4770,
name=tlog.0000000000000000006.1562631229429252096}, {size=4770,
name=tlog.0000000000000000007.1562631229487972352}, {size=4770,
name=tlog.0000000000000000008.1562631229550886912}, {size=4770,
name=tlog.0000000000000000009.1562631229624287232}, {size=4870,
name=tlog.0000000000000000010.1562631233532329984}, {size=4870,
name=tlog.0000000000000000011.1562631233691713536}, {size=4870,
name=tlog.0000000000000000012.1562631233765113856}, {
[...truncated too long message...]
9425]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSchemaAPI
[junit4] 2> 2773245 INFO
(SUITE-TestUseDocValuesAsStored2-seed#[96DFF943D4A29425]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2773245 INFO
(SUITE-TestUseDocValuesAsStored2-seed#[96DFF943D4A29425]-worker) [ ]
o.a.s.SolrTestCaseJ4 -------------------------------------------------------
Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=ar-BH,
timezone=US/Aleutian
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_121
(32-bit)/cpus=3,threads=1,free=115821664,total=450965504
[junit4] 2> NOTE: All tests run in this JVM: [TestNumericTerms32,
TestQuerySenderNoQuery, TestRangeQuery, TestPKIAuthenticationPlugin,
DistributedFacetExistsSmallTest, TestSegmentSorting,
FieldMutatingUpdateProcessorTest, CollectionsAPISolrJTest,
SpellCheckComponentTest, LeaderElectionIntegrationTest, StressHdfsTest,
DistributedSuggestComponentTest, BasicDistributedZk2Test, TestSystemIdResolver,
DeleteNodeTest, TestExportWriter, TestFastWriter, TestReloadDeadlock,
TestExclusionRuleCollectionAccess, TestWriterPerf, TestPivotHelperCode,
SSLMigrationTest, DistanceFunctionTest, TestSchemaResource,
CoreAdminHandlerTest, TestRandomFaceting, WrapperMergePolicyFactoryTest,
TestCryptoKeys, CacheHeaderTest, TestCollationFieldDocValues,
RecoveryAfterSoftCommitTest, BigEndianAscendingWordDeserializerTest,
FullHLLTest, TestMinMaxOnMultiValuedField, BufferStoreTest,
FieldAnalysisRequestHandlerTest, SmileWriterTest, ZkStateReaderTest,
MinimalSchemaTest, TestDefaultSearchFieldResource,
HdfsChaosMonkeyNothingIsSafeTest, TestFieldResource, TestCloudRecovery,
TestSizeLimitedDistributedMap, DistribCursorPagingTest, AutoCommitTest,
HdfsLockFactoryTest, TestTrie, TestHashQParserPlugin,
FileBasedSpellCheckerTest, TestSearchPerf, ZkSolrClientTest,
TestManagedSynonymFilterFactory, TestClassNameShortening,
TestNamedUpdateProcessors, TestTolerantUpdateProcessorRandomCloud,
CdcrVersionReplicationTest, TestUnifiedSolrHighlighter, DebugComponentTest,
TestSolrDeletionPolicy2, MissingSegmentRecoveryTest,
TestPerFieldSimilarityClassic, TestRealTimeGet, StatsComponentTest,
UpdateParamsTest, SuggesterWFSTTest, SolrIndexSplitterTest,
CollectionTooManyReplicasTest, SolrCoreCheckLockOnStartupTest,
BadIndexSchemaTest, QueryParsingTest, DirectoryFactoryTest,
TestSystemCollAutoCreate, ForceLeaderTest, CollectionReloadTest, TestPathTrie,
TestDistributedMissingSort, ShowFileRequestHandlerTest, TestTestInjection,
TestFieldCacheWithThreads, HighlighterConfigTest, SliceStateTest,
DistributedFacetPivotSmallTest, UpdateRequestProcessorFactoryTest,
TestDynamicFieldResource, SolrGangliaReporterTest, SolrMetricReporterTest,
JsonLoaderTest, TestConfig, HdfsUnloadDistributedZkTest, TestCoreDiscovery,
TestHdfsBackupRestoreCore, TestSimpleQParserPlugin, TestIndexingPerformance,
SpellingQueryConverterTest, TestPHPSerializedResponseWriter,
TestCloudDeleteByQuery, TestRTimerTree, TestLockTree,
TestDistributedStatsComponentCardinality, TestQueryTypes,
TestLeaderElectionWithEmptyReplica, SegmentsInfoRequestHandlerTest,
TestSolrXml, DistribJoinFromCollectionTest, TestBinaryResponseWriter,
WordBreakSolrSpellCheckerTest, SolrXmlInZkTest, RAMDirectoryFactoryTest,
TestJmxMonitoredMap, SearchHandlerTest, TestStressLiveNodes, CopyFieldTest,
TestDFRSimilarityFactory, CoreAdminCreateDiscoverTest,
ClassificationUpdateProcessorFactoryTest, ParsingFieldUpdateProcessorsTest,
TestCodecSupport, TestEmbeddedSolrServerSchemaAPI,
TestSolrQueryParserDefaultOperatorResource, JSONWriterTest,
TestAtomicUpdateErrorCases, TestPushWriter, TestSuggestSpellingConverter,
CollectionsAPIDistributedZkTest, TestSolrConfigHandler,
SpellPossibilityIteratorTest, BlockJoinFacetDistribTest,
DistributedDebugComponentTest, CurrencyFieldOpenExchangeTest,
TestSolrQueryParser, DistributedIntervalFacetingTest,
DistributedVersionInfoTest, SuggesterTSTTest, TestExceedMaxTermLength,
TestStressCloudBlindAtomicUpdates, ConfigureRecoveryStrategyTest,
OutOfBoxZkACLAndCredentialsProvidersTest, TestJsonRequest,
HdfsRestartWhileUpdatingTest, BinaryUpdateRequestHandlerTest,
TestRandomCollapseQParserPlugin, TestInPlaceUpdatesDistrib,
SignatureUpdateProcessorFactoryTest, TestMergePolicyConfig,
SuggestComponentContextFilterQueryTest, TestSubQueryTransformerCrossCore,
TestOverriddenPrefixQueryForCustomFieldType, CdcrReplicationHandlerTest,
TestInitParams, ShardSplitTest, BitVectorTest, DeleteShardTest,
TestSortByMinMaxFunction, RestartWhileUpdatingTest,
TestImpersonationWithHadoopAuth, TestScoreJoinQPNoScore, TestRecovery,
DocExpirationUpdateProcessorFactoryTest, NoCacheHeaderTest,
TestFieldCacheSanityChecker, ConfigSetsAPITest, TestValueSourceCache,
RegexBoostProcessorTest, ClassificationUpdateProcessorTest,
TestMaxScoreQueryParser, TestZkChroot, ZkCLITest, ShardRoutingCustomTest,
TestDistributedSearch, QueryElevationComponentTest, TestFiltering,
TestFunctionQuery, TestBadConfig, DistributedQueryElevationComponentTest,
TestStressLucene, TestCSVLoader, SchemaVersionSpecificBehaviorTest,
SortByFunctionTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest,
DisMaxRequestHandlerTest, TestQueryUtils, StandardRequestHandlerTest,
DirectSolrSpellCheckerTest, PrimitiveFieldTypeTest, TestOmitPositions,
DocumentBuilderTest, TermVectorComponentTest, IndexSchemaRuntimeFieldTest,
IndexSchemaTest, JSONWriterTest, TestAnalyzedSuggestions,
CSVRequestHandlerTest, TestComponentsName, TestLFUCache, SynonymTokenizerTest,
TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory,
TimeZoneUtilsTest, ResourceLoaderTest, URLClassifyProcessorTest,
DateMathParserTest, PreAnalyzedFieldTest, DateFieldTest, ClusterStateTest,
TestSolrJ, TestLRUCache, TestUtils, ZkNodePropsTest, SystemInfoHandlerTest,
UUIDFieldTest, FileUtilsTest, CircularListTest, DistributedMLTComponentTest,
TestRTGBase, CursorPagingTest, TestCrossCoreJoin,
TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping,
TestSimpleTrackingShardHandler, TestTolerantSearch,
TestEmbeddedSolrServerConstructors, TestJettySolrRunner, AliasIntegrationTest,
CdcrReplicationDistributedZkTest, CleanupOldIndexTest,
CloudExitableDirectoryReaderTest, CollectionStateFormat2Test,
ConnectionManagerTest, CreateCollectionCleanupTest, DeleteInactiveReplicaTest,
DeleteReplicaTest, DistribDocExpirationUpdateProcessorTest,
DistributedQueueTest, LeaderElectionContextKeyTest,
LeaderFailoverAfterPartitionTest, OverriddenZkACLAndCredentialsProvidersTest,
OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest,
OverseerRolesTest, OverseerTaskQueueTest, ReplicationFactorTest,
SharedFSAutoReplicaFailoverTest, SharedFSAutoReplicaFailoverUtilsTest,
SimpleCollectionCreateDeleteTest, SolrCLIZkUtilsTest, SolrCloudExampleTest,
TestCollectionAPI, TestDownShardTolerantSearch, TestLocalFSCloudBackupRestore,
TestMiniSolrCloudCluster, TestReplicaProperties, TestSSLRandomization,
TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt,
CachingDirectoryFactoryTest, HdfsDirectoryFactoryTest,
TestBackupRepositoryFactory, TestConfigOverlay, TestConfigSetImmutable,
TestConfigSetProperties, TestConfigSets, TestCorePropertiesReload,
TestCustomStream, TestImplicitCoreProperties, TestReloadAndDeleteDocs,
TestSolrDynamicMBean, TestSolrCloudSnapshots, RequestLoggingTest,
TestBlobHandler, TestSQLHandlerNonCloud, TestSolrConfigHandlerCloud,
DistributedFacetPivotSmallAdvancedTest, DistributedFacetPivotWhiteBoxTest,
DistributedQueryComponentOptimizationTest, ShufflingReplicaListTransformerTest,
JavabinLoaderTest, HighlighterMaxOffsetTest, JvmMetricsTest,
SolrCoreMetricManagerTest, SolrMetricsIntegrationTest,
SolrGraphiteReporterTest, TestRawTransformer, TestChildDocTransformer,
TestManagedResource, TestRestManager, TestBulkSchemaAPI, BooleanFieldTest,
DateRangeFieldTest, EnumFieldTest, ManagedSchemaRoundRobinCloudTest,
TestCloudManagedSchema, TestCloudSchemaless, TestSchemaManager,
TestSchemalessBufferedUpdates, TestUseDocValuesAsStored2]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestUseDocValuesAsStored2 -Dtests.seed=96DFF943D4A29425
-Dtests.slow=true -Dtests.locale=ar-BH -Dtests.timezone=US/Aleutian
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestUseDocValuesAsStored2 (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the
following files (in the order of attempts):
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores\core:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores\core
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003\cores
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.schema.TestUseDocValuesAsStored2_96DFF943D4A29425-001\tempDir-003
[junit4] > at
__randomizedtesting.SeedInfo.seed([96DFF943D4A29425]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:323)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [621/700 (2!)] on J1 in 1.94s, 1 test, 1 error <<<
FAILURES!
[...truncated 55174 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
