Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/869/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.CollectionStateFormat2Test.test
Error Message:
Could not find collection:.system
Stack Trace:
java.lang.AssertionError: Could not find collection:.system
at
__randomizedtesting.SeedInfo.seed([F35D4CAB8F8F8441:7B0973712173E9B9]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:153)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:138)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:133)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:856)
at
org.apache.solr.cloud.CollectionStateFormat2Test.testConfNameAndCollectionNameSame(CollectionStateFormat2Test.java:53)
at
org.apache.solr.cloud.CollectionStateFormat2Test.test(CollectionStateFormat2Test.java:40)
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:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
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:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
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:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10563 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionStateFormat2Test
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/init-core-data-001
[junit4] 2> 53622 INFO
(SUITE-CollectionStateFormat2Test-seed#[F35D4CAB8F8F8441]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 53622 INFO
(SUITE-CollectionStateFormat2Test-seed#[F35D4CAB8F8F8441]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_yw/m
[junit4] 2> 53624 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 53625 INFO (Thread-37) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 53625 INFO (Thread-37) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 53725 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.ZkTestServer start zk server on port:59272
[junit4] 2> 53736 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 53746 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 53764 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractZkTestCase put
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 53768 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractZkTestCase put
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 53772 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractZkTestCase put
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 53775 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractZkTestCase put
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 53778 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractZkTestCase put
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 53780 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractZkTestCase put
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 53783 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractZkTestCase put
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 53786 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractZkTestCase put
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 53788 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractZkTestCase put
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 53791 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractZkTestCase put
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 53794 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractZkTestCase put
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 53937 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/control-001/cores/collection1
[junit4] 2> 53939 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 53941 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@41c4870b{/_yw/m,null,AVAILABLE}
[junit4] 2> 53947 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@26c1962c{HTTP/1.1,[http/1.1]}{127.0.0.1:55293}
[junit4] 2> 53947 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.Server Started @56938ms
[junit4] 2> 53947 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/tempDir-001/control/data,
hostContext=/_yw/m, hostPort=55293,
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/control-001/cores}
[junit4] 2> 53947 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 53947 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 53948 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 53948 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-09-27T00:03:40.490Z
[junit4] 2> 53952 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 53955 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 53955 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/control-001/solr.xml
[junit4] 2> 53966 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59272/solr
[junit4] 2> 53969 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 53974 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 53997 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.c.ZkStateReader Updating cluster state
from ZooKeeper...
[junit4] 2> 53999 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (0)
[junit4] 2> 54007 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.OverseerElectionContext I am going to
be the leader 127.0.0.1:55293__yw%2Fm
[junit4] 2> 54009 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.Overseer Overseer
(id=96661315267330052-127.0.0.1:55293__yw%2Fm-n_0000000000) starting
[junit4] 2> 54022 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55293__yw%2Fm
[junit4] 2> 54025 INFO
(OverseerStateUpdate-96661315267330052-127.0.0.1:55293__yw%2Fm-n_0000000000)
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 54028 INFO
(zkCallback-83-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (1)
[junit4] 2> 54045 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/control-001/cores
[junit4] 2> 54045 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 54051 INFO
(OverseerStateUpdate-96661315267330052-127.0.0.1:55293__yw%2Fm-n_0000000000)
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 55064 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection x:collection1] o.a.s.c.Config
loaded config solrconfig.xml with version 0
[junit4] 2> 55076 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 55083 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection x:collection1]
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 55092 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 55308 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 55309 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] unique key field: id
[junit4] 2> 55343 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 55343 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/control-001/cores/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/control-001/cores/collection1/data/]
[junit4] 2> 55343 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2452de5e
[junit4] 2> 55344 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null
new=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/control-001/cores/collection1/data/index/
[junit4] 2> 55344 WARN
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory
'/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 55346 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6465394931917114]
[junit4] 2> 55348 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55293__yw%2Fm
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.DirectoryFactory
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/control-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 55351 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 55351 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 55351 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 55351 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 55351 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 55351 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 55352 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 55352 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 55352 WARN
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler
{type = requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 55363 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.PluginBag [collection1] Initialized 25 plugins of type
requestHandler: [/update, /update/json, /update/csv, /update/json/docs,
/config, /schema, /replication, /get, /admin/ping, /admin/segments,
/admin/luke, /admin/system, /admin/mbeans, /admin/plugins, /admin/threads,
/admin/properties, /admin/logging, /admin/file, /export, /graph, /stream, /sql,
/terms, standard, /dump]
[junit4] 2> 55363 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
[junit4] 2> 55363 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 55363 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 55364 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 55364 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 55365 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=50.7529296875,
floorSegmentMB=0.84375, forceMergeDeletesPctAllowed=8.405881902616528,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 55365 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@79916461[collection1] main]
[junit4] 2> 55366 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based
storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 55367 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO
with znodeBase: /configs/conf1
[junit4] 2> 55367 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 55367 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 55368 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode
/configs/conf1/_rest_managed.json
[junit4] 2> 55368 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path
_rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 55368 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 55368 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 55369 INFO
(searcherExecutor-99-thread-1-processing-n:127.0.0.1:55293__yw%2Fm
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@79916461[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 55370 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed
version buckets
[junit4] 2> 55370 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for
65536 version buckets from index
[junit4] 2> 55370 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed
version bucket highest value from index
[junit4] 2> 55370 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1546581046105997312
[junit4] 2> 55371 INFO
(coreLoadExecutor-98-thread-1-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with
highest version 1546581046105997312
[junit4] 2> 55382 INFO
(coreZkRegister-91-thread-1-processing-n:127.0.0.1:55293__yw%2Fm x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55293__yw%2Fm
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 55382 INFO
(coreZkRegister-91-thread-1-processing-n:127.0.0.1:55293__yw%2Fm x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55293__yw%2Fm
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 55382 INFO
(coreZkRegister-91-thread-1-processing-n:127.0.0.1:55293__yw%2Fm x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55293__yw%2Fm
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:55293/_yw/m/collection1/
[junit4] 2> 55382 INFO
(coreZkRegister-91-thread-1-processing-n:127.0.0.1:55293__yw%2Fm x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55293__yw%2Fm
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 55382 INFO
(coreZkRegister-91-thread-1-processing-n:127.0.0.1:55293__yw%2Fm x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55293__yw%2Fm
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:55293/_yw/m/collection1/ has no replicas
[junit4] 2> 55390 INFO
(coreZkRegister-91-thread-1-processing-n:127.0.0.1:55293__yw%2Fm x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55293__yw%2Fm
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:55293/_yw/m/collection1/ shard1
[junit4] 2> 55544 INFO
(coreZkRegister-91-thread-1-processing-n:127.0.0.1:55293__yw%2Fm x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55293__yw%2Fm
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 55557 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55557 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 55560 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 55562 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 55562 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 55565 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55701 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-1-001/cores/collection1
[junit4] 2> 55702 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-1-001
[junit4] 2> 55703 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 55705 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@ac0ecbc{/_yw/m,null,AVAILABLE}
[junit4] 2> 55706 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@277e73f8{HTTP/1.1,[http/1.1]}{127.0.0.1:61564}
[junit4] 2> 55707 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.Server Started @58698ms
[junit4] 2> 55707 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/_yw/m, hostPort=61564,
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-1-001/cores}
[junit4] 2> 55707 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 55707 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 55707 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 55708 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-09-27T00:03:42.249Z
[junit4] 2> 55711 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55714 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 55714 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-1-001/solr.xml
[junit4] 2> 55724 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59272/solr
[junit4] 2> 55727 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 55733 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 55741 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader Updating cluster state
from ZooKeeper...
[junit4] 2> 55744 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 55751 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:61564__yw%2Fm
[junit4] 2> 55758 INFO (zkCallback-87-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 55759 INFO
(zkCallback-83-thread-3-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 55761 INFO
(zkCallback-93-thread-2-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 55793 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-1-001/cores
[junit4] 2> 55793 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 55798 INFO
(OverseerStateUpdate-96661315267330052-127.0.0.1:55293__yw%2Fm-n_0000000000)
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 55902 INFO
(zkCallback-93-thread-2-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 56804 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 x:collection1] o.a.s.c.Config loaded
config solrconfig.xml with version 0
[junit4] 2> 56822 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 56830 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 x:collection1] o.a.s.c.SolrConfig
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 56838 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 56923 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 56924 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] unique key field: id
[junit4] 2> 56950 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 56950 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-1-001/cores/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 56950 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2452de5e
[junit4] 2> 56951 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.SolrCore New index directory detected: old=null
new=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-1-001/cores/collection1/data/index/
[junit4] 2> 56951 WARN
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.SolrCore [collection1] Solr index directory
'/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-1-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 56952 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6465394931917114]
[junit4] 2> 56953 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:61564__yw%2Fm
c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-1-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 56957 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 56957 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 56957 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 56957 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 56957 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 56957 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-explicit"
[junit4] 2> 56958 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 56958 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 56958 WARN
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 56973 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.PluginBag [collection1] Initialized 25 plugins of type requestHandler:
[/update, /update/json, /update/csv, /update/json/docs, /config, /schema,
/replication, /get, /admin/ping, /admin/segments, /admin/luke, /admin/system,
/admin/mbeans, /admin/plugins, /admin/threads, /admin/properties,
/admin/logging, /admin/file, /export, /graph, /stream, /sql, /terms, standard,
/dump]
[junit4] 2> 56973 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
[junit4] 2> 56973 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 56973 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 56974 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 56974 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 56976 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=50.7529296875,
floorSegmentMB=0.84375, forceMergeDeletesPctAllowed=8.405881902616528,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 56976 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@6bffd6a9[collection1] main]
[junit4] 2> 56978 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the
RestManager with znodeBase: /configs/conf1
[junit4] 2> 56978 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 56978 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 56979 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 56979 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage No data found for znode
/configs/conf1/_rest_managed.json
[junit4] 2> 56979 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 56979 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 56979 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 56984 INFO
(searcherExecutor-110-thread-1-processing-n:127.0.0.1:61564__yw%2Fm
x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:61564__yw%2Fm
c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@6bffd6a9[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 56984 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 56984 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version
buckets from index
[junit4] 2> 56984 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket
highest value from index
[junit4] 2> 56984 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1546581047798398976
[junit4] 2> 56985 INFO
(coreLoadExecutor-109-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm c:collection1 s:shard2 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version
1546581047798398976
[junit4] 2> 57000 INFO
(coreZkRegister-104-thread-1-processing-n:127.0.0.1:61564__yw%2Fm x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:61564__yw%2Fm c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 57000 INFO
(coreZkRegister-104-thread-1-processing-n:127.0.0.1:61564__yw%2Fm x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:61564__yw%2Fm c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may
be the new leader - try and sync
[junit4] 2> 57000 INFO
(coreZkRegister-104-thread-1-processing-n:127.0.0.1:61564__yw%2Fm x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:61564__yw%2Fm c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:61564/_yw/m/collection1/
[junit4] 2> 57000 INFO
(coreZkRegister-104-thread-1-processing-n:127.0.0.1:61564__yw%2Fm x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:61564__yw%2Fm c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 57000 INFO
(coreZkRegister-104-thread-1-processing-n:127.0.0.1:61564__yw%2Fm x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:61564__yw%2Fm c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:61564/_yw/m/collection1/ has no replicas
[junit4] 2> 57009 INFO
(coreZkRegister-104-thread-1-processing-n:127.0.0.1:61564__yw%2Fm x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:61564__yw%2Fm c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am
the new leader: http://127.0.0.1:61564/_yw/m/collection1/ shard2
[junit4] 2> 57115 INFO
(zkCallback-93-thread-2-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 57163 INFO
(coreZkRegister-104-thread-1-processing-n:127.0.0.1:61564__yw%2Fm x:collection1
s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:61564__yw%2Fm c:collection1
s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 57271 INFO
(zkCallback-93-thread-2-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 57454 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-2-001/cores/collection1
[junit4] 2> 57456 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-2-001
[junit4] 2> 57456 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 57458 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@18b1e12b{/_yw/m,null,AVAILABLE}
[junit4] 2> 57458 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@54dbfc06{HTTP/1.1,[http/1.1]}{127.0.0.1:54032}
[junit4] 2> 57458 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.Server Started @60450ms
[junit4] 2> 57458 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/tempDir-001/jetty2,
solrconfig=solrconfig.xml, hostContext=/_yw/m, hostPort=54032,
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-2-001/cores}
[junit4] 2> 57459 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 57459 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 57459 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 57459 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-09-27T00:03:44.001Z
[junit4] 2> 57468 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 57471 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 57471 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-2-001/solr.xml
[junit4] 2> 57482 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59272/solr
[junit4] 2> 57488 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 57498 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 57513 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.c.ZkStateReader Updating cluster state
from ZooKeeper...
[junit4] 2> 57515 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 57528 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:54032__yw%2Fm
[junit4] 2> 57533 INFO
(zkCallback-93-thread-2-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 57535 INFO (zkCallback-87-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 57535 INFO
(zkCallback-83-thread-3-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 57538 INFO
(zkCallback-99-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 57570 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-2-001/cores
[junit4] 2> 57570 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 57577 INFO
(OverseerStateUpdate-96661315267330052-127.0.0.1:55293__yw%2Fm-n_0000000000)
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 57682 INFO
(zkCallback-93-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 57682 INFO
(zkCallback-99-thread-2-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 58585 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 x:collection1] o.a.s.c.Config loaded
config solrconfig.xml with version 0
[junit4] 2> 58597 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 58606 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 x:collection1] o.a.s.c.SolrConfig
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 58613 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 58780 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 58782 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] unique key field: id
[junit4] 2> 58808 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 58809 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-2-001/cores/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 58809 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2452de5e
[junit4] 2> 58810 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.SolrCore New index directory detected: old=null
new=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-2-001/cores/collection1/data/index/
[junit4] 2> 58810 WARN
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.SolrCore [collection1] Solr index directory
'/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-2-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 58810 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6465394931917114]
[junit4] 2> 58812 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:54032__yw%2Fm
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.DirectoryFactory
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-2-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 58815 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 58816 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 58816 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 58816 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 58816 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 58816 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-explicit"
[junit4] 2> 58817 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 58817 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 58817 WARN
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 58830 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.PluginBag [collection1] Initialized 25 plugins of type requestHandler:
[/update, /update/json, /update/csv, /update/json/docs, /config, /schema,
/replication, /get, /admin/ping, /admin/segments, /admin/luke, /admin/system,
/admin/mbeans, /admin/plugins, /admin/threads, /admin/properties,
/admin/logging, /admin/file, /export, /graph, /stream, /sql, /terms, standard,
/dump]
[junit4] 2> 58830 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file,/terms
[junit4] 2> 58830 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 58830 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 58831 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 58831 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 58832 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=50.7529296875,
floorSegmentMB=0.84375, forceMergeDeletesPctAllowed=8.405881902616528,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 58833 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@7cefe200[collection1] main]
[junit4] 2> 58835 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the
RestManager with znodeBase: /configs/conf1
[junit4] 2> 58835 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 58835 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 58835 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 58836 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage No data found for znode
/configs/conf1/_rest_managed.json
[junit4] 2> 58836 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 58836 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 58837 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 58837 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 58837 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version
buckets from index
[junit4] 2> 58837 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket
highest value from index
[junit4] 2> 58837 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1546581049741410304
[junit4] 2> 58838 INFO
(coreLoadExecutor-120-thread-1-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version
1546581049741410304
[junit4] 2> 58843 INFO
(searcherExecutor-121-thread-1-processing-n:127.0.0.1:54032__yw%2Fm
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:54032__yw%2Fm
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@7cefe200[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 58855 INFO
(coreZkRegister-115-thread-1-processing-n:127.0.0.1:54032__yw%2Fm x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:54032__yw%2Fm c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 58855 INFO
(coreZkRegister-115-thread-1-processing-n:127.0.0.1:54032__yw%2Fm x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:54032__yw%2Fm c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I may
be the new leader - try and sync
[junit4] 2> 58855 INFO
(coreZkRegister-115-thread-1-processing-n:127.0.0.1:54032__yw%2Fm x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:54032__yw%2Fm c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:54032/_yw/m/collection1/
[junit4] 2> 58855 INFO
(coreZkRegister-115-thread-1-processing-n:127.0.0.1:54032__yw%2Fm x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:54032__yw%2Fm c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 58855 INFO
(coreZkRegister-115-thread-1-processing-n:127.0.0.1:54032__yw%2Fm x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:54032__yw%2Fm c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:54032/_yw/m/collection1/ has no replicas
[junit4] 2> 58864 INFO
(coreZkRegister-115-thread-1-processing-n:127.0.0.1:54032__yw%2Fm x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:54032__yw%2Fm c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I am
the new leader: http://127.0.0.1:54032/_yw/m/collection1/ shard1
[junit4] 2> 58970 INFO
(zkCallback-99-thread-2-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 58970 INFO
(zkCallback-93-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 59017 INFO
(coreZkRegister-115-thread-1-processing-n:127.0.0.1:54032__yw%2Fm x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:54032__yw%2Fm c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 59126 INFO
(zkCallback-93-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 59127 INFO
(zkCallback-99-thread-2-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 59221 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-3-001/cores/collection1
[junit4] 2> 59226 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-3-001
[junit4] 2> 59227 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 59230 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@11c41382{/_yw/m,null,AVAILABLE}
[junit4] 2> 59231 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@1074a1c9{HTTP/1.1,[http/1.1]}{127.0.0.1:54152}
[junit4] 2> 59232 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.Server Started @62223ms
[junit4] 2> 59232 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/tempDir-001/jetty3,
solrconfig=solrconfig.xml, hostContext=/_yw/m, hostPort=54152,
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-3-001/cores}
[junit4] 2> 59232 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 59232 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 59232 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 59232 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-09-27T00:03:45.774Z
[junit4] 2> 59236 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 59240 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 59240 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-3-001/solr.xml
[junit4] 2> 59248 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59272/solr
[junit4] 2> 59252 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 59259 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54152__yw%2Fm ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 59268 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54152__yw%2Fm ] o.a.s.c.c.ZkStateReader Updating cluster state
from ZooKeeper...
[junit4] 2> 59270 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54152__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 59279 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54152__yw%2Fm ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:54152__yw%2Fm
[junit4] 2> 59282 INFO
(zkCallback-83-thread-2-processing-n:127.0.0.1:55293__yw%2Fm)
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 59283 INFO
(zkCallback-99-thread-2-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 59283 INFO
(zkCallback-93-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 59283 INFO (zkCallback-87-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 59286 INFO
(zkCallback-105-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 59311 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54152__yw%2Fm ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-3-001/cores
[junit4] 2> 59311 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441])
[n:127.0.0.1:54152__yw%2Fm ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 59317 INFO
(OverseerStateUpdate-96661315267330052-127.0.0.1:55293__yw%2Fm-n_0000000000)
[n:127.0.0.1:55293__yw%2Fm ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard2
[junit4] 2> 59422 INFO
(zkCallback-99-thread-2-processing-n:127.0.0.1:54032__yw%2Fm)
[n:127.0.0.1:54032__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 59422 INFO
(zkCallback-93-thread-1-processing-n:127.0.0.1:61564__yw%2Fm)
[n:127.0.0.1:61564__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 59423 INFO
(zkCallback-105-thread-2-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [4])
[junit4] 2> 60323 INFO
(coreLoadExecutor-131-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 x:collection1] o.a.s.c.Config loaded
config solrconfig.xml with version 0
[junit4] 2> 60335 INFO
(coreLoadExecutor-131-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 60343 INFO
(coreLoadExecutor-131-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 x:collection1] o.a.s.c.SolrConfig
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 60350 INFO
(coreLoadExecutor-131-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 60427 INFO
(coreLoadExecutor-131-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 60428 INFO
(coreLoadExecutor-131-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] unique key field: id
[junit4] 2> 60455 INFO
(coreLoadExecutor-131-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 60456 INFO
(coreLoadExecutor-131-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-3-001/cores/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 60456 INFO
(coreLoadExecutor-131-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring
[...truncated too long message...]
:40131__yw%2Fm-n_0000000004) starting
[junit4] 2> 111231 INFO
(OverseerStateUpdate-96661315267330066-127.0.0.1:40131__yw%2Fm-n_0000000004)
[n:127.0.0.1:40131__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (1)
[junit4] 2> 111236 INFO
(zkCallback-112-thread-1-processing-n:127.0.0.1:40131__yw%2Fm)
[n:127.0.0.1:40131__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 111260 INFO
(zkCallback-112-thread-3-processing-n:127.0.0.1:40131__yw%2Fm)
[n:127.0.0.1:40131__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 111377 WARN
(OverseerStateUpdate-96661315267330066-127.0.0.1:40131__yw%2Fm-n_0000000004)
[n:127.0.0.1:40131__yw%2Fm ] o.a.s.c.Overseer Bad version writing to ZK
using compare-and-set, will force refresh cluster state: KeeperErrorCode =
BadVersion for /collections/collection1/state.json
[junit4] 2> 111380 INFO
(OverseerStateUpdate-96661315267330066-127.0.0.1:40131__yw%2Fm-n_0000000004)
[n:127.0.0.1:40131__yw%2Fm ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (1)
[junit4] 2> 111390 INFO
(zkCallback-112-thread-3-processing-n:127.0.0.1:40131__yw%2Fm)
[n:127.0.0.1:40131__yw%2Fm ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 112101 WARN
(zkCallback-105-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.c.SyncStrategy Closed, skipping sync up.
[junit4] 2> 112101 INFO
(zkCallback-105-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 112101 INFO
(zkCallback-105-thread-1-processing-n:127.0.0.1:54152__yw%2Fm)
[n:127.0.0.1:54152__yw%2Fm c:collection1 s:shard2 r:core_node3 x:collection1]
o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@61cae671
[junit4] 2> 112114 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.ServerConnector Stopped
ServerConnector@1074a1c9{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 112115 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@11c41382{/_yw/m,null,UNAVAILABLE}
[junit4] 2> 112117 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.ChaosMonkey monkey: stop shard! 40131
[junit4] 2> 112117 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=887589440
[junit4] 2> 112129 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.Overseer Overseer
(id=96661315267330066-127.0.0.1:40131__yw%2Fm-n_0000000004) closing
[junit4] 2> 112130 INFO
(OverseerStateUpdate-96661315267330066-127.0.0.1:40131__yw%2Fm-n_0000000004)
[n:127.0.0.1:40131__yw%2Fm ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:40131__yw%2Fm
[junit4] 2> 112136 WARN
(zkCallback-112-thread-1-processing-n:127.0.0.1:40131__yw%2Fm)
[n:127.0.0.1:40131__yw%2Fm ] o.a.s.c.c.ZkStateReader ZooKeeper watch
triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for
/live_nodes]
[junit4] 2> 113638 WARN
(zkCallback-112-thread-3-processing-n:127.0.0.1:40131__yw%2Fm)
[n:127.0.0.1:40131__yw%2Fm ] o.a.s.c.c.ZkStateReader ZooKeeper watch
triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for
/clusterstate.json]
[junit4] 2> 113667 WARN
(zkCallback-112-thread-2-processing-n:127.0.0.1:40131__yw%2Fm)
[n:127.0.0.1:40131__yw%2Fm c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.SyncStrategy Closed, skipping sync up.
[junit4] 2> 113667 INFO
(zkCallback-112-thread-2-processing-n:127.0.0.1:40131__yw%2Fm)
[n:127.0.0.1:40131__yw%2Fm c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 113667 INFO
(zkCallback-112-thread-2-processing-n:127.0.0.1:40131__yw%2Fm)
[n:127.0.0.1:40131__yw%2Fm c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@57ba77b2
[junit4] 2> 113682 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.ServerConnector Stopped
ServerConnector@3881a9e{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 113682 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2266b73a{/_yw/m,null,UNAVAILABLE}
[junit4] 2> 113689 INFO
(TEST-CollectionStateFormat2Test.test-seed#[F35D4CAB8F8F8441]) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:59272 59272
[junit4] 2> 113803 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxnFactory Ignoring unexpected runtime exception
[junit4] 2> java.nio.channels.CancelledKeyException
[junit4] 2> at
sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
[junit4] 2> at
sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:187)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 113804 INFO (Thread-37) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:59272 59272
[junit4] 2> 113807 WARN (Thread-37) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/aliases.json
[junit4] 2> 8 /solr/clusterprops.json
[junit4] 2> 5 /solr/security.json
[junit4] 2> 5 /solr/configs/conf1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/clusterstate.json
[junit4] 2> 4 /solr/collections/collection1/state.json
[junit4] 2> 4 /solr/collections/myExternColl/state.json
[junit4] 2> 2 /solr/collections/.system/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 60 /solr/overseer/queue
[junit4] 2> 50 /solr/overseer/collection-queue-work
[junit4] 2> 19 /solr/overseer/queue-work
[junit4] 2> 8 /solr/live_nodes
[junit4] 2> 8 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=CollectionStateFormat2Test -Dtests.method=test
-Dtests.seed=F35D4CAB8F8F8441 -Dtests.slow=true -Dtests.locale=ms-MY
-Dtests.timezone=Africa/Accra -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] FAILURE 60.2s J1 | CollectionStateFormat2Test.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not find
collection:.system
[junit4] > at
__randomizedtesting.SeedInfo.seed([F35D4CAB8F8F8441:7B0973712173E9B9]:0)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:153)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:138)
[junit4] > at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:133)
[junit4] > at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:856)
[junit4] > at
org.apache.solr.cloud.CollectionStateFormat2Test.testConfNameAndCollectionNameSame(CollectionStateFormat2Test.java:53)
[junit4] > at
org.apache.solr.cloud.CollectionStateFormat2Test.test(CollectionStateFormat2Test.java:40)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985)
[junit4] > at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 113814 INFO
(SUITE-CollectionStateFormat2Test-seed#[F35D4CAB8F8F8441]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionStateFormat2Test_F35D4CAB8F8F8441-001
[junit4] 2> Sep 27, 2016 12:04:40 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {},
docValues:{}, maxPointsInLeafNode=476, maxMBSortInHeap=6.418434158654158,
sim=RandomSimilarity(queryNorm=false): {}, locale=ms-MY, timezone=Africa/Accra
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_102
(64-bit)/cpus=3,threads=1,free=83321584,total=249561088
[junit4] 2> NOTE: All tests run in this JVM: [TestCollectionAPI,
CollectionStateFormat2Test]
[junit4] Completed [32/633 (1!)] on J1 in 61.03s, 1 test, 1 failure <<<
FAILURES!
[...truncated 62173 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
