Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Solaris/710/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.CdcrBootstrapTest.testBootstrapWithSourceCluster
Error Message:
Document mismatch on target after sync expected:<2000> but was:<1000>
Stack Trace:
java.lang.AssertionError: Document mismatch on target after sync
expected:<2000> but was:<1000>
at
__randomizedtesting.SeedInfo.seed([48351F47AE662088:91634E83AD0233C2]: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.testBootstrapWithSourceCluster(CdcrBootstrapTest.java:232)
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)
Build Log:
[...truncated 12520 lines...]
[junit4] Suite: org.apache.solr.cloud.CdcrBootstrapTest
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001/init-core-data-001
[junit4] 2> 1703238 INFO
(SUITE-CdcrBootstrapTest-seed#[48351F47AE662088]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 1703239 INFO
(SUITE-CdcrBootstrapTest-seed#[48351F47AE662088]-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> 1703241 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testBootstrapWithSourceCluster
[junit4] 2> 1703241 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001/cdcr-target-001
[junit4] 2> 1703241 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1703241 INFO (Thread-5683) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1703241 INFO (Thread-5683) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1703341 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.c.ZkTestServer start zk server on port:61550
[junit4] 2> 1703349 INFO (jetty-launcher-3504-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1703351 INFO (jetty-launcher-3504-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@69445049{/solr,null,AVAILABLE}
[junit4] 2> 1703351 INFO (jetty-launcher-3504-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@729ea8fe{HTTP/1.1,[http/1.1]}{127.0.0.1:44270}
[junit4] 2> 1703351 INFO (jetty-launcher-3504-thread-1) [ ]
o.e.j.s.Server Started @1708304ms
[junit4] 2> 1703351 INFO (jetty-launcher-3504-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=44270}
[junit4] 2> 1703352 ERROR (jetty-launcher-3504-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1703352 INFO (jetty-launcher-3504-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.5.0
[junit4] 2> 1703352 INFO (jetty-launcher-3504-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1703352 INFO (jetty-launcher-3504-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1703352 INFO (jetty-launcher-3504-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-03-07T17:13:01.921Z
[junit4] 2> 1703354 INFO (jetty-launcher-3504-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1703361 INFO (jetty-launcher-3504-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1703362 INFO (jetty-launcher-3504-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61550/solr
[junit4] 2> 1703397 INFO (jetty-launcher-3504-thread-1)
[n:127.0.0.1:44270_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1703398 INFO (jetty-launcher-3504-thread-1)
[n:127.0.0.1:44270_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:44270_solr
[junit4] 2> 1703400 INFO (jetty-launcher-3504-thread-1)
[n:127.0.0.1:44270_solr ] o.a.s.c.Overseer Overseer
(id=97576994852765699-127.0.0.1:44270_solr-n_0000000000) starting
[junit4] 2> 1703410 INFO (jetty-launcher-3504-thread-1)
[n:127.0.0.1:44270_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44270_solr
[junit4] 2> 1703413 INFO
(OverseerStateUpdate-97576994852765699-127.0.0.1:44270_solr-n_0000000000)
[n:127.0.0.1:44270_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1703476 INFO (jetty-launcher-3504-thread-1)
[n:127.0.0.1:44270_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001/cdcr-target-001/node1/.
[junit4] 1> Target zkHost = 127.0.0.1:61550/solr
[junit4] 2> 1703842 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001/cdcr-source-001
[junit4] 2> 1703842 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1703842 INFO (Thread-5693) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1703842 INFO (Thread-5693) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1703845 WARN (Thread-5693) [ ] o.a.z.j.MBeanRegistry
Failed to register MBean StandaloneServer_port-1
[junit4] 2> 1703845 WARN (Thread-5693) [ ] 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> 1703942 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.c.ZkTestServer start zk server on port:43008
[junit4] 2> 1703951 INFO (jetty-launcher-3516-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1703953 INFO (jetty-launcher-3516-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7b4a0cb9{/solr,null,AVAILABLE}
[junit4] 2> 1703953 INFO (jetty-launcher-3516-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@2f209abc{HTTP/1.1,[http/1.1]}{127.0.0.1:55396}
[junit4] 2> 1703953 INFO (jetty-launcher-3516-thread-1) [ ]
o.e.j.s.Server Started @1708907ms
[junit4] 2> 1703953 INFO (jetty-launcher-3516-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55396}
[junit4] 2> 1703954 ERROR (jetty-launcher-3516-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1703954 INFO (jetty-launcher-3516-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.5.0
[junit4] 2> 1703954 INFO (jetty-launcher-3516-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1703954 INFO (jetty-launcher-3516-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1703954 INFO (jetty-launcher-3516-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-03-07T17:13:02.523Z
[junit4] 2> 1703957 INFO (jetty-launcher-3516-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1703963 INFO (jetty-launcher-3516-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1703964 INFO (jetty-launcher-3516-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43008/solr
[junit4] 2> 1704004 INFO (jetty-launcher-3516-thread-1)
[n:127.0.0.1:55396_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1704006 INFO (jetty-launcher-3516-thread-1)
[n:127.0.0.1:55396_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:55396_solr
[junit4] 2> 1704007 INFO (jetty-launcher-3516-thread-1)
[n:127.0.0.1:55396_solr ] o.a.s.c.Overseer Overseer
(id=97576994892218371-127.0.0.1:55396_solr-n_0000000000) starting
[junit4] 2> 1704019 INFO (jetty-launcher-3516-thread-1)
[n:127.0.0.1:55396_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55396_solr
[junit4] 2> 1704022 INFO
(OverseerStateUpdate-97576994892218371-127.0.0.1:55396_solr-n_0000000000)
[n:127.0.0.1:55396_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1704130 INFO (jetty-launcher-3516-thread-1)
[n:127.0.0.1:55396_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001/cdcr-source-001/node1/.
[junit4] 2> 1704546 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1704548 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43008/solr
ready
[junit4] 2> 1704551 INFO (qtp230876729-19287) [n:127.0.0.1:55396_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> 1704555 INFO
(OverseerThreadFactory-7417-thread-1-processing-n:127.0.0.1:55396_solr)
[n:127.0.0.1:55396_solr ] o.a.s.c.CreateCollectionCmd Create collection
cdcr-source
[junit4] 2> 1704663 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1704770 INFO
(zkCallback-3523-thread-2-processing-n:127.0.0.1:55396_solr)
[n:127.0.0.1:55396_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> 1705675 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.5.0
[junit4] 2> 1705680 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705682 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705684 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705685 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705690 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705691 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705691 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore
[[cdcr-source_shard1_replica1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001/cdcr-source-001/node1/cdcr-source_shard1_replica1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001/cdcr-source-001/node1/./cdcr-source_shard1_replica1/data/]
[junit4] 2> 1705761 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705762 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705762 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1705762 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1705764 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@61a62010[cdcr-source_shard1_replica1] main]
[junit4] 2> 1705765 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705765 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705766 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705767 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705767 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1705774 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705779 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705803 INFO
(searcherExecutor-7420-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SolrCore
[cdcr-source_shard1_replica1] Registered new searcher
Searcher@61a62010[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1705803 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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
1561231920329654272
[junit4] 2> 1705812 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1705812 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705812 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:55396/solr/cdcr-source_shard1_replica1/
[junit4] 2> 1705812 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705812 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 x:cdcr-source_shard1_replica1] o.a.s.c.SyncStrategy
http://127.0.0.1:55396/solr/cdcr-source_shard1_replica1/ has no replicas
[junit4] 2> 1705812 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705818 INFO
(zkCallback-3523-thread-2-processing-n:127.0.0.1:55396_solr)
[n:127.0.0.1:55396_solr ] o.a.s.h.CdcrLeaderStateManager Received new leader
state @ cdcr-source:shard1
[junit4] 2> 1705820 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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:55396/solr/cdcr-source_shard1_replica1/ shard1
[junit4] 2> 1705931 INFO
(zkCallback-3523-thread-1-processing-n:127.0.0.1:55396_solr)
[n:127.0.0.1:55396_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> 1705981 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1705984 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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=1322
[junit4] 2> 1705994 INFO (qtp230876729-19287) [n:127.0.0.1:55396_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> 1706090 INFO
(zkCallback-3523-thread-3-processing-n:127.0.0.1:55396_solr)
[n:127.0.0.1:55396_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> 1706558 INFO
(OverseerCollectionConfigSetProcessor-97576994892218371-127.0.0.1:55396_solr-n_0000000000)
[n:127.0.0.1:55396_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> 1706996 INFO (qtp230876729-19287) [n:127.0.0.1:55396_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=2446
[junit4] 1> Adding 10 docs with commit=true, numDocs=100
[junit4] 2> 1707010 INFO (qtp230876729-19290) [n:127.0.0.1:55396_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=8
[junit4] 2> 1707013 INFO (qtp230876729-19291) [n:127.0.0.1:55396_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> 1707014 INFO (qtp230876729-19291) [n:127.0.0.1:55396_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@10c4327f
[junit4] 2> 1707025 INFO (qtp230876729-19291) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@233b408d[cdcr-source_shard1_replica1] main]
[junit4] 2> 1707025 INFO (qtp230876729-19291) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1707025 INFO
(searcherExecutor-7420-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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@233b408d[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C100)))}
[junit4] 2> 1707026 INFO (qtp230876729-19291) [n:127.0.0.1:55396_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=12
[junit4] 1> Adding 10 docs with commit=true, numDocs=200
[junit4] 2> 1707033 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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> 1707035 INFO (qtp230876729-19285) [n:127.0.0.1:55396_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> 1707035 INFO (qtp230876729-19285) [n:127.0.0.1:55396_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@10c4327f
[junit4] 2> 1707046 INFO (qtp230876729-19285) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@2ab39857[cdcr-source_shard1_replica1] main]
[junit4] 2> 1707046 INFO
(searcherExecutor-7420-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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@2ab39857[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C100)
Uninverting(_1(6.5.0):C100)))}
[junit4] 2> 1707046 INFO (qtp230876729-19285) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1707046 INFO (qtp230876729-19285) [n:127.0.0.1:55396_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=11
[junit4] 1> Adding 10 docs with commit=true, numDocs=300
[junit4] 2> 1707052 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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=3
[junit4] 2> 1707055 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1707055 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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@10c4327f
[junit4] 2> 1707066 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@2b10858d[cdcr-source_shard1_replica1] main]
[junit4] 2> 1707067 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1707067 INFO
(searcherExecutor-7420-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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@2b10858d[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C100)
Uninverting(_1(6.5.0):C100) Uninverting(_2(6.5.0):C100)))}
[junit4] 2> 1707067 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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=12
[junit4] 1> Adding 10 docs with commit=true, numDocs=400
[junit4] 2> 1707072 INFO (qtp230876729-19287) [n:127.0.0.1:55396_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=3
[junit4] 2> 1707074 INFO (qtp230876729-19290) [n:127.0.0.1:55396_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> 1707074 INFO (qtp230876729-19290) [n:127.0.0.1:55396_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@10c4327f
[junit4] 2> 1707087 INFO (qtp230876729-19290) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4528277e[cdcr-source_shard1_replica1] main]
[junit4] 2> 1707087 INFO (qtp230876729-19290) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1707087 INFO
(searcherExecutor-7420-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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@4528277e[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C100)
Uninverting(_1(6.5.0):C100) Uninverting(_2(6.5.0):C100)
Uninverting(_3(6.5.0):C100)))}
[junit4] 2> 1707087 INFO (qtp230876729-19290) [n:127.0.0.1:55396_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=13
[junit4] 1> Adding 10 docs with commit=true, numDocs=500
[junit4] 2> 1707093 INFO (qtp230876729-19291) [n:127.0.0.1:55396_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=2
[junit4] 2> 1707095 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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> 1707095 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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@10c4327f
[junit4] 2> 1707108 INFO (qtp230876729-19292) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@28e62b72[cdcr-source_shard1_replica1] main]
[junit4] 2> 1707108 INFO (qtp230876729-19292) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1707109 INFO
(searcherExecutor-7420-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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@28e62b72[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C100)
Uninverting(_1(6.5.0):C100) Uninverting(_2(6.5.0):C100)
Uninverting(_3(6.5.0):C100) Uninverting(_4(6.5.0):C100)))}
[junit4] 2> 1707109 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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=13
[junit4] 1> Adding 10 docs with commit=true, numDocs=600
[junit4] 2> 1707114 INFO (qtp230876729-19285) [n:127.0.0.1:55396_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=2
[junit4] 2> 1707115 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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> 1707115 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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@10c4327f
[junit4] 2> 1707129 INFO (qtp230876729-19288) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4d84da65[cdcr-source_shard1_replica1] main]
[junit4] 2> 1707129 INFO (qtp230876729-19288) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1707129 INFO
(searcherExecutor-7420-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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@4d84da65[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C100)
Uninverting(_1(6.5.0):C100) Uninverting(_2(6.5.0):C100)
Uninverting(_3(6.5.0):C100) Uninverting(_4(6.5.0):C100)
Uninverting(_5(6.5.0):C100)))}
[junit4] 2> 1707129 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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=14
[junit4] 1> Adding 10 docs with commit=true, numDocs=700
[junit4] 2> 1707134 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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=2
[junit4] 2> 1707136 INFO (qtp230876729-19287) [n:127.0.0.1:55396_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> 1707136 INFO (qtp230876729-19287) [n:127.0.0.1:55396_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@10c4327f
[junit4] 2> 1707150 INFO (qtp230876729-19287) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@315fea2[cdcr-source_shard1_replica1] main]
[junit4] 2> 1707150 INFO (qtp230876729-19287) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1707151 INFO
(searcherExecutor-7420-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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@315fea2[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C100)
Uninverting(_1(6.5.0):C100) Uninverting(_2(6.5.0):C100)
Uninverting(_3(6.5.0):C100) Uninverting(_4(6.5.0):C100)
Uninverting(_5(6.5.0):C100) Uninverting(_6(6.5.0):C100)))}
[junit4] 2> 1707151 INFO (qtp230876729-19287) [n:127.0.0.1:55396_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=15
[junit4] 1> Adding 10 docs with commit=true, numDocs=800
[junit4] 2> 1707160 INFO (qtp230876729-19290) [n:127.0.0.1:55396_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=2
[junit4] 2> 1707161 INFO (qtp230876729-19291) [n:127.0.0.1:55396_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> 1707161 INFO (qtp230876729-19291) [n:127.0.0.1:55396_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@10c4327f
[junit4] 2> 1707176 INFO (qtp230876729-19291) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@16b02e6[cdcr-source_shard1_replica1] main]
[junit4] 2> 1707176 INFO (qtp230876729-19291) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1707176 INFO
(searcherExecutor-7420-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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@16b02e6[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C100)
Uninverting(_1(6.5.0):C100) Uninverting(_2(6.5.0):C100)
Uninverting(_3(6.5.0):C100) Uninverting(_4(6.5.0):C100)
Uninverting(_5(6.5.0):C100) Uninverting(_6(6.5.0):C100)
Uninverting(_7(6.5.0):C100)))}
[junit4] 2> 1707177 INFO (qtp230876729-19291) [n:127.0.0.1:55396_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=15
[junit4] 1> Adding 10 docs with commit=true, numDocs=900
[junit4] 2> 1707181 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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=2
[junit4] 2> 1707183 INFO (qtp230876729-19285) [n:127.0.0.1:55396_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> 1707183 INFO (qtp230876729-19285) [n:127.0.0.1:55396_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@10c4327f
[junit4] 2> 1707198 INFO (qtp230876729-19285) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@65e7ca2[cdcr-source_shard1_replica1] main]
[junit4] 2> 1707199 INFO
(searcherExecutor-7420-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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@65e7ca2[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C100)
Uninverting(_1(6.5.0):C100) Uninverting(_2(6.5.0):C100)
Uninverting(_3(6.5.0):C100) Uninverting(_4(6.5.0):C100)
Uninverting(_5(6.5.0):C100) Uninverting(_6(6.5.0):C100)
Uninverting(_7(6.5.0):C100) Uninverting(_8(6.5.0):C100)))}
[junit4] 2> 1707199 INFO (qtp230876729-19285) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1707199 INFO (qtp230876729-19285) [n:127.0.0.1:55396_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=15
[junit4] 1> Adding 10 docs with commit=true, numDocs=1000
[junit4] 2> 1707204 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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=2
[junit4] 2> 1707206 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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> 1707206 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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@10c4327f
[junit4] 2> 1707222 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@27f85fdc[cdcr-source_shard1_replica1] main]
[junit4] 2> 1707222 INFO (qtp230876729-19289) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1707223 INFO
(searcherExecutor-7420-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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@27f85fdc[cdcr-source_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C100)
Uninverting(_1(6.5.0):C100) Uninverting(_2(6.5.0):C100)
Uninverting(_3(6.5.0):C100) Uninverting(_4(6.5.0):C100)
Uninverting(_5(6.5.0):C100) Uninverting(_6(6.5.0):C100)
Uninverting(_7(6.5.0):C100) Uninverting(_8(6.5.0):C100)
Uninverting(_9(6.5.0):C100)))}
[junit4] 2> 1707223 INFO (qtp230876729-19289) [n:127.0.0.1:55396_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=17
[junit4] 2> 1707227 INFO (qtp230876729-19287) [n:127.0.0.1:55396_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=3
[junit4] 2> 1707239 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1707240 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61550/solr
ready
[junit4] 2> 1707242 INFO (qtp827698803-19244) [n:127.0.0.1:44270_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> 1707248 INFO
(OverseerThreadFactory-7409-thread-1-processing-n:127.0.0.1:44270_solr)
[n:127.0.0.1:44270_solr ] o.a.s.c.CreateCollectionCmd Create collection
cdcr-target
[junit4] 2> 1707356 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1707463 INFO
(zkCallback-3511-thread-1-processing-n:127.0.0.1:44270_solr)
[n:127.0.0.1:44270_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> 1708373 INFO (qtp827698803-19248) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.5.0
[junit4] 2> 1708378 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708381 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708383 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708384 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708389 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708390 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708390 INFO (qtp827698803-19248) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SolrCore
[[cdcr-target_shard1_replica1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001/cdcr-target-001/node1/cdcr-target_shard1_replica1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001/cdcr-target-001/node1/./cdcr-target_shard1_replica1/data/]
[junit4] 2> 1708444 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708445 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708445 INFO (qtp827698803-19248) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 1708445 INFO (qtp827698803-19248) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 1708446 INFO (qtp827698803-19248) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@12336a68[cdcr-target_shard1_replica1] main]
[junit4] 2> 1708449 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708449 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708449 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708450 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708450 INFO (qtp827698803-19248) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1708455 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708458 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708460 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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
1561231923115720704
[junit4] 2> 1708460 INFO
(searcherExecutor-7426-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SolrCore
[cdcr-target_shard1_replica1] Registered new searcher
Searcher@12336a68[cdcr-target_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1708469 INFO (qtp827698803-19248) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1708469 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708469 INFO (qtp827698803-19248) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:44270/solr/cdcr-target_shard1_replica1/
[junit4] 2> 1708470 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708470 INFO (qtp827698803-19248) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 x:cdcr-target_shard1_replica1] o.a.s.c.SyncStrategy
http://127.0.0.1:44270/solr/cdcr-target_shard1_replica1/ has no replicas
[junit4] 2> 1708470 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708476 INFO
(zkCallback-3511-thread-3-processing-n:127.0.0.1:44270_solr)
[n:127.0.0.1:44270_solr ] o.a.s.h.CdcrLeaderStateManager Received new leader
state @ cdcr-target:shard1
[junit4] 2> 1708479 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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:44270/solr/cdcr-target_shard1_replica1/ shard1
[junit4] 2> 1708585 INFO
(zkCallback-3511-thread-2-processing-n:127.0.0.1:44270_solr)
[n:127.0.0.1:44270_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> 1708631 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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> 1708634 INFO (qtp827698803-19248) [n:127.0.0.1:44270_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=1279
[junit4] 2> 1708641 INFO (qtp827698803-19244) [n:127.0.0.1:44270_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> 1708742 INFO
(zkCallback-3511-thread-1-processing-n:127.0.0.1:44270_solr)
[n:127.0.0.1:44270_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> 1709250 INFO
(OverseerCollectionConfigSetProcessor-97576994852765699-127.0.0.1:44270_solr-n_0000000000)
[n:127.0.0.1:44270_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> 1709642 INFO (qtp827698803-19244) [n:127.0.0.1:44270_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=2401
[junit4] 2> 1709650 INFO (qtp827698803-19249) [n:127.0.0.1:44270_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=3
[junit4] 2> 1709651 INFO
(zkCallback-3511-thread-1-processing-n:127.0.0.1:44270_solr)
[n:127.0.0.1:44270_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> 1709651 INFO
(zkCallback-3511-thread-1-processing-n:127.0.0.1:44270_solr)
[n:127.0.0.1:44270_solr ] o.a.s.h.CdcrProcessStateManager Received new CDCR
process state from watcher: STARTED @ cdcr-target:shard1
[junit4] 2> 1709656 INFO (qtp230876729-19290) [n:127.0.0.1:55396_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> 1709657 INFO (qtp230876729-19290) [n:127.0.0.1:55396_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:61550/solr ready
[junit4] 2> 1709667 INFO (qtp827698803-19251) [n:127.0.0.1:44270_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> 1709668 INFO (qtp827698803-19251) [n:127.0.0.1:44270_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> 1709674 INFO (qtp827698803-19250) [n:127.0.0.1:44270_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=13
[junit4] 2> 1709677 INFO (qtp230876729-19290) [n:127.0.0.1:55396_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> 1709678 INFO (qtp230876729-19290) [n:127.0.0.1:55396_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> 1709686 INFO (qtp230876729-19290) [n:127.0.0.1:55396_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> 1709688 INFO
(cdcr-bootstrap-status-3535-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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:44270/solr/cdcr-target_shard1_replica1/
[junit4] 2> 1709701 INFO
(zkCallback-3523-thread-3-processing-n:127.0.0.1:55396_solr)
[n:127.0.0.1:55396_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> 1709702 INFO
(zkCallback-3523-thread-3-processing-n:127.0.0.1:55396_solr)
[n:127.0.0.1:55396_solr ] o.a.s.h.CdcrProcessStateManager Received new CDCR
process state from watcher: STARTED @ cdcr-source:shard1
[junit4] 2> 1709702 INFO (qtp827698803-19247) [n:127.0.0.1:44270_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:55396/solr/cdcr-source_shard1_replica1/&action=BOOTSTRAP&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 1709705 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_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> 1709707 INFO (qtp230876729-19291) [n:127.0.0.1:55396_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> 1709707 INFO (qtp230876729-19291) [n:127.0.0.1:55396_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@10c4327f
[junit4] 2> 1709710 INFO (qtp230876729-19291) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@63a456f9[cdcr-source_shard1_replica1] realtime]
[junit4] 2> 1709710 INFO (qtp230876729-19291) [n:127.0.0.1:55396_solr
c:cdcr-source s:shard1 r:core_node1 x:cdcr-source_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1709710 INFO (qtp230876729-19291) [n:127.0.0.1:55396_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=4
[junit4] 2> 1709712 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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> 1709712 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Master's generation: 12
[junit4] 2> 1709712 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Master's version:
1488906788276
[junit4] 2> 1709712 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_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> 1709712 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_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> 1709712 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1709713 INFO (qtp230876729-19290) [n:127.0.0.1:55396_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=60
[junit4] 2> 1709718 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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.1561231921587945472}, {size=4770,
name=tlog.0000000000000000001.1561231921614159872}, {size=4770,
name=tlog.0000000000000000002.1561231921636179968}, {size=4770,
name=tlog.0000000000000000003.1561231921657151488}, {size=4770,
name=tlog.0000000000000000004.1561231921680220160}, {size=4770,
name=tlog.0000000000000000005.1561231921701191680}, {size=4770,
name=tlog.0000000000000000006.1561231921722163200}, {size=4770,
name=tlog.0000000000000000007.1561231921749426176}, {size=4770,
name=tlog.0000000000000000008.1561231921772494848}, {size=4770,
name=tlog.0000000000000000009.1561231921795563520}]
[junit4] 2> 1709718 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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={generation=12&qt=/replication&wt=javabin&version=2&command=filelist}
status=0 QTime=4
[junit4] 2> 1709718 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Number of files in latest
index in master: 11
[junit4] 2> 1709718 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Number of tlog files in
master: 10
[junit4] 2> 1709719 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.u.DefaultSolrCoreState New IndexWriter is
ready to be used.
[junit4] 2> 1709720 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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=queues&wt=javabin&version=2}
status=0 QTime=0
[junit4] 1> Cdcr queue response:
{responseHeader={status=0,QTime=0},queues={127.0.0.1:61550/solr={cdcr-target={queueSize=1010,lastTimestamp=}}},tlogTotalSize=47579,tlogTotalCount=10,updateLogSynchronizer=stopped}
[junit4] 2> 1709722 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Starting download
(fullCopy=false) to MockDirectoryWrapper(RAMDirectory@6bf09622
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41ec120f)
[junit4] 2> 1709723 INFO (qtp827698803-19247) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
BUFFERING replay: false
[junit4] 2> 1709723 INFO (qtp827698803-19247) [n:127.0.0.1:44270_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=/update
params={_stateVer_=cdcr-target:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1709724 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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={generation=12&qt=/replication&file=_a.fnm&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709729 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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={generation=12&qt=/replication&file=_a.nvm&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709731 INFO (qtp230876729-19292) [n:127.0.0.1:55396_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={generation=12&qt=/replication&file=_a.pos&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709734 INFO (qtp230876729-19291) [n:127.0.0.1:55396_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={generation=12&qt=/replication&file=_a.fdt&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709737 INFO (qtp230876729-19285) [n:127.0.0.1:55396_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={generation=12&qt=/replication&file=_a.doc&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709739 INFO (qtp230876729-19285) [n:127.0.0.1:55396_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={generation=12&qt=/replication&file=_a.si&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709741 INFO (qtp230876729-19285) [n:127.0.0.1:55396_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={generation=12&qt=/replication&file=_a.tim&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709744 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&file=_a.tip&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709746 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&file=_a.fdx&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709748 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&file=_a.nvd&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709751 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&file=segments_c&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709751 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Starting download of tlog
files from master: [{size=4649,
name=tlog.0000000000000000000.1561231921587945472}, {size=4770,
name=tlog.0000000000000000001.1561231921614159872}, {size=4770,
name=tlog.0000000000000000002.1561231921636179968}, {size=4770,
name=tlog.0000000000000000003.1561231921657151488}, {size=4770,
name=tlog.0000000000000000004.1561231921680220160}, {size=4770,
name=tlog.0000000000000000005.1561231921701191680}, {size=4770,
name=tlog.0000000000000000006.1561231921722163200}, {size=4770,
name=tlog.0000000000000000007.1561231921749426176}, {size=4770,
name=tlog.0000000000000000008.1561231921772494848}, {size=4770,
name=tlog.0000000000000000009.1561231921795563520}]
[junit4] 2> 1709754 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&checksum=true&tlogFile=tlog.0000000000000000000.1561231921587945472&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709757 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&checksum=true&tlogFile=tlog.0000000000000000001.1561231921614159872&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709759 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&checksum=true&tlogFile=tlog.0000000000000000002.1561231921636179968&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709762 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&checksum=true&tlogFile=tlog.0000000000000000003.1561231921657151488&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709764 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&checksum=true&tlogFile=tlog.0000000000000000004.1561231921680220160&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709766 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&checksum=true&tlogFile=tlog.0000000000000000005.1561231921701191680&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709769 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&checksum=true&tlogFile=tlog.0000000000000000006.1561231921722163200&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709771 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&checksum=true&tlogFile=tlog.0000000000000000007.1561231921749426176&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709775 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&checksum=true&tlogFile=tlog.0000000000000000008.1561231921772494848&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709777 INFO (qtp230876729-19288) [n:127.0.0.1:55396_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={generation=12&qt=/replication&checksum=true&tlogFile=tlog.0000000000000000009.1561231921795563520&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1709778 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Total time taken for
download (fullCopy=false,bytesDownloaded=92635) : 0 secs (null bytes/sec) to
MockDirectoryWrapper(RAMDirectory@6bf09622
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41ec120f)
[junit4] 2> 1709785 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.u.DefaultSolrCoreState New IndexWriter is
ready to be used.
[junit4] 2> 1709785 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.IndexFetcher Reloading SolrCore
cdcr-target_shard1_replica1
[junit4] 2> 1709786 INFO (qtp827698803-19247) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4b87022c[cdcr-target_shard1_replica1] main]
[junit4] 2> 1709786 INFO (qtp827698803-19247) [n:127.0.0.1:44270_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=62
[junit4] 2> 1709787 INFO
(cdcr-bootstrap-status-3535-thread-1-processing-n:127.0.0.1:55396_solr
x:cdcr-source_shard1_replica1 s:shard1 c:cdcr-source r:core_node1)
[n:127.0.0.1:55396_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> 1709788 INFO
(searcherExecutor-7426-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.c.SolrCore [cdcr-target_shard1_replica1]
Registered new searcher Searcher@4b87022c[cdcr-target_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.5.0):C1000)))}
[junit4] 2> 1709788 INFO (qtp827698803-19249) [n:127.0.0.1:44270_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=/select
params={q=*:*&_stateVer_=cdcr-target:3&wt=javabin&version=2} hits=1000 status=0
QTime=63
[junit4] 2> 1709796 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 1709801 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.s.IndexSchema [cdcr-target_shard1_replica1] Schema name=minimal
[junit4] 2> 1709803 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1709805 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.CoreContainer Reloading SolrCore 'cdcr-target_shard1_replica1' using
configuration from collection cdcr-target
[junit4] 2> 1709805 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.SolrCore [[cdcr-target_shard1_replica1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001/cdcr-target-001/node1/cdcr-target_shard1_replica1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001/cdcr-target-001/node1/./cdcr-target_shard1_replica1/data/]
[junit4] 2> 1709846 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1709847 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1709848 INFO (qtp827698803-19246) [n:127.0.0.1:44270_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=CANCEL_BOOTSTRAP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1709854 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@5c240ce0[cdcr-target_shard1_replica1] main]
[junit4] 2> 1709856 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/cdcr-target
[junit4] 2> 1709857 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 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> 1709857 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 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> 1709857 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1709858 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1709863 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1709873 INFO
(searcherExecutor-7438-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.c.SolrCore [cdcr-target_shard1_replica1]
Registered new searcher Searcher@5c240ce0[cdcr-target_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.5.0):C1000)))}
[junit4] 2> 1709873 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@66f2bd[cdcr-target_shard1_replica1]
main]
[junit4] 2> 1709873 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.c.SolrCore [cdcr-target_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@613f491a
[junit4] 2> 1709874 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.h.CdcrRequestHandler Solr core is being closed - shutting down CDCR
handler @ cdcr-target:shard1
[junit4] 2> 1709875 INFO
(searcherExecutor-7438-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.c.SolrCore [cdcr-target_shard1_replica1]
Registered new searcher Searcher@66f2bd[cdcr-target_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_a(6.5.0):C1000)))}
[junit4] 2> 1709875 INFO (Thread-5704) [n:127.0.0.1:44270_solr
c:cdcr-target s:shard1 r:core_node1 x:cdcr-target_shard1_replica1]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.cdcr-target.shard1.replica1
[junit4] 2> 1709876 INFO
(recoveryExecutor-3509-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.CdcrRequestHandler No replay needed.
[junit4] 2> 1709878 ERROR
(updateExecutor-3508-thread-1-processing-n:127.0.0.1:44270_solr
x:cdcr-target_shard1_replica1 s:shard1 c:cdcr-target r:core_node1)
[n:127.0.0.1:44270_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.CdcrRequestHandler Bootstrap operation
failed
[junit4] 2> java.util.concurrent.ExecutionException:
java.lang.AssertionError
[junit4] 2> at
java.util.concurrent.FutureTask.report(FutureTask.java:122)
[junit4] 2> at
java.util.concurrent.FutureTask.get(FutureTask.java:192)
[junit4] 2> at
org.apache.solr.handler.CdcrRequestHandler.lambda$handleBootstrapAction$0(CdcrRequestHandler.java:643)
[junit4] 2> at
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.lang.AssertionError
[junit4] 2> at org.apache.solr.handler.Cd
[...truncated too long message...]
erseer/collection-queue-work
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2> 2 /solr/live_nodes
[junit4] 2> 2 /solr/collections
[junit4] 2>
[junit4] 2> 1857508 INFO (jetty-closer-3581-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@62818f82{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1857509 INFO (jetty-closer-3581-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=194718635
[junit4] 2> 1857512 INFO (coreCloseExecutor-7561-thread-1)
[n:127.0.0.1:50420_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.c.SolrCore [cdcr-target_shard1_replica1]
CLOSING SolrCore org.apache.solr.core.SolrCore@10616ffc
[junit4] 2> 1857512 INFO (coreCloseExecutor-7561-thread-1)
[n:127.0.0.1:50420_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.h.CdcrRequestHandler Solr core is being
closed - shutting down CDCR handler @ cdcr-target:shard1
[junit4] 2> 1857514 INFO (coreCloseExecutor-7561-thread-1)
[n:127.0.0.1:50420_solr c:cdcr-target s:shard1 r:core_node1
x:cdcr-target_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.cdcr-target.shard1.replica1
[junit4] 2> 1857514 INFO
(zkCallback-3587-thread-3-processing-n:127.0.0.1:50420_solr)
[n:127.0.0.1:50420_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> 1857516 INFO (jetty-closer-3581-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=97577004109201411-127.0.0.1:50420_solr-n_0000000000) closing
[junit4] 2> 1857516 INFO
(OverseerStateUpdate-97577004109201411-127.0.0.1:50420_solr-n_0000000000)
[n:127.0.0.1:50420_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:50420_solr
[junit4] 2> 1857518 WARN
(zkCallback-3587-thread-3-processing-n:127.0.0.1:50420_solr)
[n:127.0.0.1:50420_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1857520 INFO (jetty-closer-3581-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1857520 INFO (jetty-closer-3581-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2138fe70{/solr,null,UNAVAILABLE}
[junit4] 2> 1857524 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42291 42291
[junit4] 2> 1857541 INFO (Thread-5741) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:42291 42291
[junit4] 2> 1857543 WARN (Thread-5741) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/configs/cdcr-target/managed-schema
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 3
/solr/collections/cdcr-target/leaders/shard1/leader
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/collections/cdcr-target/cdcr/state/process
[junit4] 2> 3 /solr/collections/cdcr-target/cdcr/state/buffer
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 11 /solr/overseer/collection-queue-work
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> 1857543 INFO
(TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[48351F47AE662088])
[ ] o.a.s.SolrTestCaseJ4 ###Ending
testBootstrapWithContinousIndexingOnSourceCluster
[junit4] 2> 1857544 INFO
(SUITE-CdcrBootstrapTest-seed#[48351F47AE662088]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.CdcrBootstrapTest_48351F47AE662088-001
[junit4] 2> Mar 07, 2017 5:15:36 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 4 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=es-CU,
timezone=Europe/Skopje
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_121
(64-bit)/cpus=3,threads=1,free=153873864,total=492830720
[junit4] 2> NOTE: All tests run in this JVM: [BlobRepositoryCloudTest,
PolyFieldTest, LeaderInitiatedRecoveryOnCommitTest,
UniqFieldsUpdateProcessorFactoryTest, TestCollationFieldDocValues,
TestCustomStream, PeerSyncReplicationTest, TestEmbeddedSolrServerConstructors,
TestRequestForwarding, TestRTimerTree, TestSolrFieldCacheMBean,
TestHdfsCloudBackupRestore, TestClassNameShortening, TestSystemCollAutoCreate,
TestDeleteCollectionOnDownNodes, TestTestInjection, TestGroupingSearch,
TestFieldCacheSortRandom, SuggesterFSTTest, DateFieldTest, RequiredFieldsTest,
XmlUpdateRequestHandlerTest, VMParamsZkACLAndCredentialsProvidersTest,
TestRandomDVFaceting, TlogReplayBufferedWhileIndexingTest, TestStressRecovery,
EnumFieldTest, TestMinMaxOnMultiValuedField, TestJsonFacetRefinement,
DocValuesMultiTest, TestSolr4Spatial2, BitVectorTest, TestBlobHandler,
TestBlendedInfixSuggestions, TestDocumentBuilder, HdfsRecoveryZkTest,
TestPerFieldSimilarityClassic, DOMUtilTest, TestPostingsSolrHighlighter,
JavabinLoaderTest, TestSolrDeletionPolicy1, LeaderFailoverAfterPartitionTest,
SaslZkACLProviderTest, TestCloudInspectUtil, TestRawTransformer,
TestXIncludeConfig, TestComplexPhraseQParserPlugin, TestFieldCacheWithThreads,
TestRandomCollapseQParserPlugin, BlockJoinFacetDistribTest,
TestLeaderElectionZkExpiry, TestSQLHandler, TestJsonRequest,
TestPhraseSuggestions, HighlighterTest, TestConfigSets, DirectoryFactoryTest,
TestSolrCloudWithSecureImpersonation, ResponseLogComponentTest,
TestLocalFSCloudBackupRestore, UpdateLogTest,
DistribDocExpirationUpdateProcessorTest, HardAutoCommitTest,
PreAnalyzedFieldManagedSchemaCloudTest, TestUnifiedSolrHighlighter,
ZkStateWriterTest, MetricUtilsTest, TestDefaultSearchFieldResource,
DocExpirationUpdateProcessorFactoryTest, DirectSolrConnectionTest,
TestPushWriter, DataDrivenBlockJoinTest, DeleteReplicaTest,
CdcrReplicationDistributedZkTest, TestMacroExpander, FullHLLTest,
TestExactStatsCache, TestMiniSolrCloudClusterSSL, MultiThreadedOCPTest,
TestSolrIndexConfig, OverseerModifyCollectionTest, TestManagedResource,
TestSchemalessBufferedUpdates, RollingRestartTest, BlockCacheTest,
UUIDUpdateProcessorFallbackTest, TestDistributedSearch, TestFieldTypeResource,
TestSimpleQParserPlugin, DistributedFacetPivotLongTailTest, TestConfigSetsAPI,
TestExtendedDismaxParser, TestConfigSetImmutable, SolrCloudExampleTest,
ShardRoutingTest, DisMaxRequestHandlerTest, DeleteLastCustomShardedReplicaTest,
TestXmlQParser, QueryElevationComponentTest, TestCollationField,
ZkControllerTest, TestNamedUpdateProcessors, TestSolrCoreSnapshots,
DistributedFacetExistsSmallTest, SystemInfoHandlerTest,
AnalysisErrorHandlingTest, OverseerTest, HttpSolrCallGetCoreTest,
DistributedSpellCheckComponentTest, ZkCLITest, StandardRequestHandlerTest,
TestIBSimilarityFactory, TestManagedSynonymFilterFactory, TestMissingGroups,
TestTolerantUpdateProcessorCloud, TestHighlightDedupGrouping,
TestSolrCloudWithDelegationTokens, PathHierarchyTokenizerFactoryTest,
QueryEqualityTest, BadComponentTest, SimpleCollectionCreateDeleteTest,
JSONWriterTest, TestCollapseQParserPlugin, ConnectionManagerTest,
TestHdfsBackupRestoreCore, TestDownShardTolerantSearch, TestLazyCores,
TestPerFieldSimilarity, TestWordDelimiterFilterFactory, SolrJmxReporterTest,
TestElisionMultitermQuery, DistributedMLTComponentTest, TestStressVersions,
TestClusterProperties, DistributedQueryElevationComponentTest,
ClassificationUpdateProcessorTest, TestBinaryResponseWriter,
TermsComponentTest, DocValuesTest, TestLRUCache, TestExportWriter, TestSolrXml,
TestRestManager, TestDistribIDF, AnalysisAfterCoreReloadTest,
CustomCollectionTest, MergeStrategyTest,
StatelessScriptUpdateProcessorFactoryTest, ZkNodePropsTest,
SolrRequestParserTest, SolrIndexMetricsTest, TestAuthenticationFramework,
TestInitQParser, TestCharFilters, HdfsSyncSliceTest, TestBulkSchemaConcurrent,
MigrateRouteKeyTest, TestReRankQParserPlugin, TestConfigsApi,
DistributedQueryComponentCustomSortTest, TestFastLRUCache, JsonValidatorTest,
CoreMergeIndexesAdminHandlerTest, TestHashPartitioner, HdfsLockFactoryTest,
CoreAdminCreateDiscoverTest, DeleteStatusTest,
HdfsWriteToMultipleCollectionsTest, JSONWriterTest, OpenCloseCoreStressTest,
RecoveryZkTest, TestReplicationHandler, AutoCommitTest, TestFaceting,
TestRecovery, TestStressReorder, TestCoreContainer, SimpleFacetsTest,
StatsComponentTest, PeerSyncTest, DirectUpdateHandlerTest, SoftAutoCommitTest,
TestPartialUpdateDeduplication, PingRequestHandlerTest, SOLR749Test,
TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest,
CopyFieldTest, SolrIndexConfigTest, MultiTermTest, SampleTest, TestDocSet,
TestBinaryField, TestSearchPerf, NumericFieldsTest, OutputWriterTest,
TestSolrCoreProperties, TestLuceneMatchVersion, SpellPossibilityIteratorTest,
TestCodecSupport, SynonymTokenizerTest, EchoParamsTest,
TestSweetSpotSimilarityFactory, TestBM25SimilarityFactory, TimeZoneUtilsTest,
TestFastOutputStream, OpenExchangeRatesOrgProviderTest, PluginInfoTest,
ChaosMonkeyNothingIsSafeTest, BaseCdcrDistributedZkTest, CdcrBootstrapTest]
[junit4] Completed [497/697 (1!)] on J0 in 155.34s, 3 tests, 1 failure <<<
FAILURES!
[...truncated 63340 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
