Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/862/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.TestCryptoKeys.test
Error Message:
Could not find collection:.system
Stack Trace:
java.lang.AssertionError: Could not find collection:.system
at
__randomizedtesting.SeedInfo.seed([6780679EFCB48229:EFD458445248EFD1]: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.TestCryptoKeys.test(TestCryptoKeys.java:117)
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 11383 lines...]
[junit4] Suite: org.apache.solr.cloud.TestCryptoKeys
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_6780679EFCB48229-001/init-core-data-001
[junit4] 2> 1420864 INFO
(SUITE-TestCryptoKeys-seed#[6780679EFCB48229]-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> 1420864 INFO
(SUITE-TestCryptoKeys-seed#[6780679EFCB48229]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1420866 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.a.s.c.ZkTestServer
STARTING ZK TEST SERVER
[junit4] 2> 1420867 INFO (Thread-2664) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1420867 INFO (Thread-2664) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1420967 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.a.s.c.ZkTestServer
start zk server on port:35378
[junit4] 2> 1420971 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1420978 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1420989 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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> 1420993 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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> 1420995 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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> 1420998 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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> 1421001 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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> 1421003 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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> 1421005 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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> 1421008 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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> 1421012 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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> 1421014 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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> 1421017 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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> 1421176 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] 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.TestCryptoKeys_6780679EFCB48229-001/control-001/cores/collection1
[junit4] 2> 1421177 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.e.j.s.Server
jetty-9.3.8.v20160314
[junit4] 2> 1421180 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2a0b9786{/,null,AVAILABLE}
[junit4] 2> 1421184 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@2d46786{HTTP/1.1,[http/1.1]}{127.0.0.1:56644}
[junit4] 2> 1421184 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.e.j.s.Server
Started @1424956ms
[junit4] 2> 1421184 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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.TestCryptoKeys_6780679EFCB48229-001/tempDir-001/control/data,
hostContext=/, hostPort=56644,
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.TestCryptoKeys_6780679EFCB48229-001/control-001/cores}
[junit4] 2> 1421186 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1421188 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1421188 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] 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.TestCryptoKeys_6780679EFCB48229-001/control-001/solr.xml
[junit4] 2> 1421199 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:35378/solr
[junit4] 2> 1421201 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1421206 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:56644_ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1421232 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:56644_ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1421234 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:56644_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0)
[junit4] 2> 1421243 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:56644_ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56644_
[junit4] 2> 1421245 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:56644_ ]
o.a.s.c.Overseer Overseer (id=96642765221462020-127.0.0.1:56644_-n_0000000000)
starting
[junit4] 2> 1421260 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:56644_ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:56644_
[junit4] 2> 1421285 INFO
(OverseerStateUpdate-96642765221462020-127.0.0.1:56644_-n_0000000000)
[n:127.0.0.1:56644_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1421318 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:56644_ ]
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.TestCryptoKeys_6780679EFCB48229-001/control-001/cores
[junit4] 2> 1421318 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:56644_ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1421325 INFO
(OverseerStateUpdate-96642765221462020-127.0.0.1:56644_-n_0000000000)
[n:127.0.0.1:56644_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 1422331 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection x:collection1] o.a.s.c.Config loaded
config solrconfig.xml with version 0
[junit4] 2> 1422339 WARN
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 1422340 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1422349 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1422355 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 1422447 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422447 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] unique key field: id
[junit4] 2> 1422467 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 1422467 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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.TestCryptoKeys_6780679EFCB48229-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.TestCryptoKeys_6780679EFCB48229-001/control-001/cores/collection1/data/]
[junit4] 2> 1422467 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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@730d155b
[junit4] 2> 1422468 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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.TestCryptoKeys_6780679EFCB48229-001/control-001/cores/collection1/data/index/
[junit4] 2> 1422468 WARN
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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.TestCryptoKeys_6780679EFCB48229-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 1422468 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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=47, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1422468 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4e097285
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@37259f52),segFN=segments_1,generation=1}
[junit4] 2> 1422468 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1422469 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:56644_
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.TestCryptoKeys_6780679EFCB48229-001/control-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 1422472 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 1422472 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 1422472 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1422472 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 1422472 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1422472 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422472 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422472 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422473 WARN
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422479 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422479 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422480 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422480 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422481 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1422481 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1422481 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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=38, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1422481 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4e097285
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@37259f52),segFN=segments_1,generation=1}
[junit4] 2> 1422481 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1422482 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@35c934de[collection1] main]
[junit4] 2> 1422483 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422483 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1422483 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1422483 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422484 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422484 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422484 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1422484 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1422484 INFO
(searcherExecutor-5462-thread-1-processing-n:127.0.0.1:56644_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56644_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@35c934de[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1422485 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422485 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422485 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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> 1422485 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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 1546284245237891072
[junit4] 2> 1422486 INFO
(coreLoadExecutor-5461-thread-1-processing-n:127.0.0.1:56644_)
[n:127.0.0.1:56644_ 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
1546284245237891072
[junit4] 2> 1422494 INFO
(coreZkRegister-5454-thread-1-processing-n:127.0.0.1:56644_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56644_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1
and weAreReplacement=false and leaderVoteWait=10000
[junit4] 2> 1422496 INFO
(coreZkRegister-5454-thread-1-processing-n:127.0.0.1:56644_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56644_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1422496 INFO
(coreZkRegister-5454-thread-1-processing-n:127.0.0.1:56644_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56644_
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> 1422496 INFO
(coreZkRegister-5454-thread-1-processing-n:127.0.0.1:56644_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56644_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:56644/collection1/
[junit4] 2> 1422496 INFO
(coreZkRegister-5454-thread-1-processing-n:127.0.0.1:56644_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56644_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 1422496 INFO
(coreZkRegister-5454-thread-1-processing-n:127.0.0.1:56644_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56644_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:56644/collection1/ has no replicas
[junit4] 2> 1422501 INFO
(coreZkRegister-5454-thread-1-processing-n:127.0.0.1:56644_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56644_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/control_collection/leaders/shard1/leader after winning as
/collections/control_collection/leader_elect/shard1/election/96642765221462020-core_node1-n_0000000000
[junit4] 2> 1422502 INFO
(coreZkRegister-5454-thread-1-processing-n:127.0.0.1:56644_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56644_
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:56644/collection1/ shard1
[junit4] 2> 1422658 INFO
(coreZkRegister-5454-thread-1-processing-n:127.0.0.1:56644_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56644_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 1422827 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1422827 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1422831 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1422833 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.a.s.c.ChaosMonkey
monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1422833 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 1422851 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1423000 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] 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.TestCryptoKeys_6780679EFCB48229-001/shard-1-001/cores/collection1
[junit4] 2> 1423000 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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.TestCryptoKeys_6780679EFCB48229-001/shard-1-001
[junit4] 2> 1423001 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.e.j.s.Server
jetty-9.3.8.v20160314
[junit4] 2> 1423003 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@e51da12{/,null,AVAILABLE}
[junit4] 2> 1423003 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@300963c9{HTTP/1.1,[http/1.1]}{127.0.0.1:55927}
[junit4] 2> 1423003 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.e.j.s.Server
Started @1426776ms
[junit4] 2> 1423003 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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.TestCryptoKeys_6780679EFCB48229-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/, hostPort=55927,
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_6780679EFCB48229-001/shard-1-001/cores}
[junit4] 2> 1423006 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1423008 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1423008 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] 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.TestCryptoKeys_6780679EFCB48229-001/shard-1-001/solr.xml
[junit4] 2> 1423017 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:35378/solr
[junit4] 2> 1423019 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1423023 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:55927_ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1423032 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:55927_ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1423034 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:55927_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1423043 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:55927_ ]
o.a.s.c.ZkController Publish node=127.0.0.1:55927_ as DOWN
[junit4] 2> 1423045 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:55927_ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55927_
[junit4] 2> 1423046 INFO
(OverseerStateUpdate-96642765221462020-127.0.0.1:56644_-n_0000000000)
[n:127.0.0.1:56644_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node:
127.0.0.1:55927_
[junit4] 2> 1423047 INFO
(zkCallback-1883-thread-1-processing-n:127.0.0.1:56644_) [n:127.0.0.1:56644_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1423049 INFO (zkCallback-1887-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1423050 INFO
(zkCallback-1893-thread-1-processing-n:127.0.0.1:55927_) [n:127.0.0.1:55927_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1423075 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:55927_ ]
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.TestCryptoKeys_6780679EFCB48229-001/shard-1-001/cores
[junit4] 2> 1423075 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:55927_ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1423078 INFO
(OverseerStateUpdate-96642765221462020-127.0.0.1:56644_-n_0000000000)
[n:127.0.0.1:56644_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 1423184 INFO
(zkCallback-1893-thread-2-processing-n:127.0.0.1:55927_) [n:127.0.0.1:55927_
] 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> 1424087 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 x:collection1] o.a.s.c.Config loaded config
solrconfig.xml with version 0
[junit4] 2> 1424097 WARN
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1424098 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 1424106 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded
SolrConfig: solrconfig.xml
[junit4] 2> 1424114 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 1424250 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ 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> 1424251 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] unique key field: id
[junit4] 2> 1424300 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1424300 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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.TestCryptoKeys_6780679EFCB48229-001/shard-1-001/cores/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_6780679EFCB48229-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 1424300 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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@730d155b
[junit4] 2> 1424302 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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/temp/solr.cloud.TestCryptoKeys_6780679EFCB48229-001/shard-1-001/cores/collection1/data/index/
[junit4] 2> 1424302 WARN
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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/temp/solr.cloud.TestCryptoKeys_6780679EFCB48229-001/shard-1-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 1424302 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=47, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1424303 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1942fdeb
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c062519),segFN=segments_1,generation=1}
[junit4] 2> 1424303 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1424304 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55927_
c:collection1 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/temp/solr.cloud.TestCryptoKeys_6780679EFCB48229-001/shard-1-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 1424308 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 1424309 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 1424309 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1424309 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 1424309 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1424309 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-explicit"
[junit4] 2> 1424309 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1424309 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1424310 WARN
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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> 1424323 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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> 1424323 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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> 1424323 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1424323 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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> 1424325 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1424325 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1424326 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=38, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1424326 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1942fdeb
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c062519),segFN=segments_1,generation=1}
[junit4] 2> 1424326 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1424326 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@3998ccad[collection1] main]
[junit4] 2> 1424328 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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> 1424329 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1424329 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1424330 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1424330 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage No data found for znode
/configs/conf1/_rest_managed.json
[junit4] 2> 1424330 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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> 1424330 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1424331 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1424331 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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> 1424331 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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> 1424331 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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> 1424331 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 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 1546284247173562368
[junit4] 2> 1424332 INFO
(searcherExecutor-5473-thread-1-processing-n:127.0.0.1:55927_ x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:55927_ c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new
searcher Searcher@3998ccad[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1424333 INFO
(coreLoadExecutor-5472-thread-1-processing-n:127.0.0.1:55927_)
[n:127.0.0.1:55927_ c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version
1546284247173562368
[junit4] 2> 1424346 INFO
(coreZkRegister-5467-thread-1-processing-n:127.0.0.1:55927_ x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:55927_ c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the
leader process for shard=shard1 and weAreReplacement=false and
leaderVoteWait=10000
[junit4] 2> 1424350 INFO
(coreZkRegister-5467-thread-1-processing-n:127.0.0.1:55927_ x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:55927_ c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas
found to continue.
[junit4] 2> 1424350 INFO
(coreZkRegister-5467-thread-1-processing-n:127.0.0.1:55927_ x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:55927_ c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new
leader - try and sync
[junit4] 2> 1424350 INFO
(coreZkRegister-5467-thread-1-processing-n:127.0.0.1:55927_ x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:55927_ c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:55927/collection1/
[junit4] 2> 1424350 INFO
(coreZkRegister-5467-thread-1-processing-n:127.0.0.1:55927_ x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:55927_ c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 1424350 INFO
(coreZkRegister-5467-thread-1-processing-n:127.0.0.1:55927_ x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:55927_ c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:55927/collection1/ has no replicas
[junit4] 2> 1424355 INFO
(coreZkRegister-5467-thread-1-processing-n:127.0.0.1:55927_ x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:55927_ c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating
leader registration node /collections/collection1/leaders/shard1/leader after
winning as
/collections/collection1/leader_elect/shard1/election/96642765221462025-core_node1-n_0000000000
[junit4] 2> 1424358 INFO
(coreZkRegister-5467-thread-1-processing-n:127.0.0.1:55927_ x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:55927_ c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new
leader: http://127.0.0.1:55927/collection1/ shard1
[junit4] 2> 1424464 INFO
(zkCallback-1893-thread-2-processing-n:127.0.0.1:55927_) [n:127.0.0.1:55927_
] 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> 1424511 INFO
(coreZkRegister-5467-thread-1-processing-n:127.0.0.1:55927_ x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:55927_ c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery
necessary
[junit4] 2> 1424619 INFO
(zkCallback-1893-thread-2-processing-n:127.0.0.1:55927_) [n:127.0.0.1:55927_
] 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> 1424790 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] 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.TestCryptoKeys_6780679EFCB48229-001/shard-2-001/cores/collection1
[junit4] 2> 1424791 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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.TestCryptoKeys_6780679EFCB48229-001/shard-2-001
[junit4] 2> 1424791 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.e.j.s.Server
jetty-9.3.8.v20160314
[junit4] 2> 1424793 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@56329225{/,null,AVAILABLE}
[junit4] 2> 1424794 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@3a50d934{HTTP/1.1,[http/1.1]}{127.0.0.1:51207}
[junit4] 2> 1424794 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.e.j.s.Server
Started @1428567ms
[junit4] 2> 1424794 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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.TestCryptoKeys_6780679EFCB48229-001/tempDir-001/jetty2,
solrconfig=solrconfig.xml, hostContext=/, hostPort=51207,
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.TestCryptoKeys_6780679EFCB48229-001/shard-2-001/cores}
[junit4] 2> 1424796 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1424797 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1424798 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] 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.TestCryptoKeys_6780679EFCB48229-001/shard-2-001/solr.xml
[junit4] 2> 1424805 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:35378/solr
[junit4] 2> 1424807 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1424810 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:51207_ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1424817 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:51207_ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1424818 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:51207_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1424822 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:51207_ ]
o.a.s.c.ZkController Publish node=127.0.0.1:51207_ as DOWN
[junit4] 2> 1424824 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:51207_ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51207_
[junit4] 2> 1424825 INFO
(OverseerStateUpdate-96642765221462020-127.0.0.1:56644_-n_0000000000)
[n:127.0.0.1:56644_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node:
127.0.0.1:51207_
[junit4] 2> 1424826 INFO
(zkCallback-1893-thread-2-processing-n:127.0.0.1:55927_) [n:127.0.0.1:55927_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1424826 INFO (zkCallback-1887-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1424826 INFO
(zkCallback-1883-thread-3-processing-n:127.0.0.1:56644_) [n:127.0.0.1:56644_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1424865 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:51207_ ]
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.TestCryptoKeys_6780679EFCB48229-001/shard-2-001/cores
[junit4] 2> 1424865 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:51207_ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1424868 INFO
(zkCallback-1899-thread-1-processing-n:127.0.0.1:51207_) [n:127.0.0.1:51207_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1424870 INFO
(OverseerStateUpdate-96642765221462020-127.0.0.1:56644_-n_0000000000)
[n:127.0.0.1:56644_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 1424975 INFO
(zkCallback-1893-thread-2-processing-n:127.0.0.1:55927_) [n:127.0.0.1:55927_
] 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> 1424978 INFO
(zkCallback-1899-thread-2-processing-n:127.0.0.1:51207_) [n:127.0.0.1:51207_
] 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> 1425877 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 x:collection1] o.a.s.c.Config loaded config
solrconfig.xml with version 0
[junit4] 2> 1425890 WARN
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1425891 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 1425904 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded
SolrConfig: solrconfig.xml
[junit4] 2> 1425910 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 1426054 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426056 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] unique key field: id
[junit4] 2> 1426082 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1426082 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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.TestCryptoKeys_6780679EFCB48229-001/shard-2-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.TestCryptoKeys_6780679EFCB48229-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 1426083 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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@730d155b
[junit4] 2> 1426083 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_6780679EFCB48229-001/shard-2-001/cores/collection1/data/index/
[junit4] 2> 1426083 WARN
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_6780679EFCB48229-001/shard-2-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 1426084 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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=47, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1426084 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14053733
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33284dff),segFN=segments_1,generation=1}
[junit4] 2> 1426084 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1426090 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 1426091 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 1426091 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1426091 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 1426091 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1426091 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-explicit"
[junit4] 2> 1426091 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 1426091 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426091 WARN
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426094 WARN
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51207_
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/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_6780679EFCB48229-001/shard-2-001/cores/collection1/data/
does not point to a valid data directory; skipping clean-up of old index
directories.
[junit4] 2> 1426104 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426104 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426105 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 1426105 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426105 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1426106 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1426106 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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=38, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.0]
[junit4] 2> 1426106 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@14053733
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33284dff),segFN=segments_1,generation=1}
[junit4] 2> 1426106 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1426106 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@2113e546[collection1] main]
[junit4] 2> 1426108 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426108 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 1426108 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1426108 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426109 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426109 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426109 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1426109 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1426109 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426109 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426109 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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> 1426109 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ 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 1546284249037930496
[junit4] 2> 1426110 INFO
(searcherExecutor-5484-thread-1-processing-n:127.0.0.1:51207_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51207_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.SolrCore [collection1] Registered new
searcher Searcher@2113e546[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1426111 INFO
(coreLoadExecutor-5483-thread-1-processing-n:127.0.0.1:51207_)
[n:127.0.0.1:51207_ c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version
1546284249037930496
[junit4] 2> 1426115 INFO
(coreZkRegister-5478-thread-1-processing-n:127.0.0.1:51207_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51207_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.ZkController Core needs to
recover:collection1
[junit4] 2> 1426116 INFO
(updateExecutor-1896-thread-1-processing-n:127.0.0.1:51207_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51207_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 1426116 INFO
(recoveryExecutor-1897-thread-1-processing-n:127.0.0.1:51207_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51207_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process.
recoveringAfterStartup=true
[junit4] 2> 1426116 INFO
(recoveryExecutor-1897-thread-1-processing-n:127.0.0.1:51207_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51207_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 1426116 INFO
(recoveryExecutor-1897-thread-1-processing-n:127.0.0.1:51207_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51207_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1]
[junit4] 2> 1426121 INFO
(recoveryExecutor-1897-thread-1-processing-n:127.0.0.1:51207_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51207_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates.
FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1426121 INFO
(recoveryExecutor-1897-thread-1-processing-n:127.0.0.1:51207_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51207_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Publishing state of core
[collection1] as recovering, leader is [http://127.0.0.1:55927/collection1/]
and I am [http://127.0.0.1:51207/collection1/]
[junit4] 2> 1426126 INFO
(recoveryExecutor-1897-thread-1-processing-n:127.0.0.1:51207_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51207_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery
command to [http://127.0.0.1:55927]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:51207_&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 1426131 INFO (qtp689932853-11824) [n:127.0.0.1:55927_ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node2, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 1426132 INFO (qtp689932853-11824) [n:127.0.0.1:55927_ ]
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard1 of collection1) have state: recovering
[junit4] 2> 1426132 INFO (qtp689932853-11824) [n:127.0.0.1:55927_ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=down, localState=active,
nodeName=127.0.0.1:51207_, coreNodeName=core_node2,
onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"http://127.0.0.1:51207","node_name":"127.0.0.1:51207_","state":"down"}
[junit4] 2> 1426238 INFO
(zkCallback-1893-thread-2-processing-n:127.0.0.1:55927_) [n:127.0.0.1:55927_
] 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> 1426239 INFO
(zkCallback-1899-thread-2-processing-n:127.0.0.1:51207_) [n:127.0.0.1:51207_
] 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> 1426537 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] 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.TestCryptoKeys_6780679EFCB48229-001/shard-3-001/cores/collection1
[junit4] 2> 1426538 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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.TestCryptoKeys_6780679EFCB48229-001/shard-3-001
[junit4] 2> 1426538 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.e.j.s.Server
jetty-9.3.8.v20160314
[junit4] 2> 1426539 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2900dabb{/,null,AVAILABLE}
[junit4] 2> 1426540 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@1e686c63{HTTP/1.1,[http/1.1]}{127.0.0.1:33351}
[junit4] 2> 1426540 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.e.j.s.Server
Started @1430314ms
[junit4] 2> 1426540 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
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.TestCryptoKeys_6780679EFCB48229-001/tempDir-001/jetty3,
solrconfig=solrconfig.xml, hostContext=/, hostPort=33351,
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_6780679EFCB48229-001/shard-3-001/cores}
[junit4] 2> 1426542 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1426544 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 1426544 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] 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.TestCryptoKeys_6780679EFCB48229-001/shard-3-001/solr.xml
[junit4] 2> 1426552 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.a.s.c.ZkContainer
Zookeeper client=127.0.0.1:35378/solr
[junit4] 2> 1426554 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1426558 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:33351_ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1426565 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:33351_ ]
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1426566 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:33351_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1426571 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:33351_ ]
o.a.s.c.ZkController Publish node=127.0.0.1:33351_ as DOWN
[junit4] 2> 1426573 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:33351_ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33351_
[junit4] 2> 1426573 INFO
(OverseerStateUpdate-96642765221462020-127.0.0.1:56644_-n_0000000000)
[n:127.0.0.1:56644_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node:
127.0.0.1:33351_
[junit4] 2> 1426581 INFO
(zkCallback-1899-thread-2-processing-n:127.0.0.1:51207_) [n:127.0.0.1:51207_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1426582 INFO
(zkCallback-1883-thread-3-processing-n:127.0.0.1:56644_) [n:127.0.0.1:56644_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1426586 INFO
(zkCallback-1893-thread-2-processing-n:127.0.0.1:55927_) [n:127.0.0.1:55927_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1426587 INFO (zkCallback-1887-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1426589 INFO
(zkCallback-1906-thread-2-processing-n:127.0.0.1:33351_) [n:127.0.0.1:33351_
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1426613 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:33351_ ]
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.TestCryptoKeys_6780679EFCB48229-001/shard-3-001/cores
[junit4] 2> 1426613 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [n:127.0.0.1:33351_ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1426617 INFO
(OverseerStateUpdate-96642765221462020-127.0.0.1:56644_-n_0000000000)
[n:127.0.0.1:56644_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 1426721 INFO
(zkCallback-1893-thread-2-processing-n:127.0.0.1:55927_) [n:127.0.0.1:55927_
] 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> 1426721 INFO
(zkCallback-1899-thread-2-processing-n:127.0.0.1:51207_) [n:127.0.0.1:51207_
] 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> 1426721 INFO
(zkCallback-1906-thread-2-processing-n:127.0.0.1:33351_) [n:127.0.0.1:33351_
] 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> 1427133 INFO (qtp689932853-11824) [n:127.0.0.1:55927_ ]
o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=recovering, localState=active,
nodeName=127.0.0.1:51207_, coreNodeName=core_node2,
onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"http://127.0.0.1:51207","node_name":"127.0.0.1:51207_","state":"recovering"}
[junit4] 2> 1427133 INFO (qtp689932853-11824) [n:127.0.0.1:55927_ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node2, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 1427134 INFO (qtp689932853-11824) [n:127.0.0.1:55927_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:51207_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node2&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1003
[junit4] 2> 1427624 INFO
(coreLoadExecutor-5494-thread-1-processing-n:127.0.0.1:33351_) [
[...truncated too long message...]
collection1/data;done=false>>]
[junit4] 2> 1467799 INFO
(zkCallback-1913-thread-3-processing-n:127.0.0.1:61248_) [n:127.0.0.1:61248_
c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1467799 INFO
(zkCallback-1913-thread-3-processing-n:127.0.0.1:61248_) [n:127.0.0.1:61248_
c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.CachingDirectoryFactory Closing 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.TestCryptoKeys_6780679EFCB48229-001/shard-4-001/cores/collection1/data
[junit4] 2> 1467799 INFO
(zkCallback-1913-thread-3-processing-n:127.0.0.1:61248_) [n:127.0.0.1:61248_
c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.CachingDirectoryFactory looking to close
/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.TestCryptoKeys_6780679EFCB48229-001/shard-4-001/cores/collection1/data/index
[CachedDir<<refCount=0;path=/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.TestCryptoKeys_6780679EFCB48229-001/shard-4-001/cores/collection1/data/index;done=false>>]
[junit4] 2> 1467799 INFO
(zkCallback-1913-thread-3-processing-n:127.0.0.1:61248_) [n:127.0.0.1:61248_
c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1467799 INFO
(zkCallback-1913-thread-3-processing-n:127.0.0.1:61248_) [n:127.0.0.1:61248_
c:collection1 s:shard1 r:core_node4 x:collection1]
o.a.s.c.CachingDirectoryFactory Closing 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.TestCryptoKeys_6780679EFCB48229-001/shard-4-001/cores/collection1/data/index
[junit4] 2> 1467799 INFO
(zkCallback-1913-thread-3-processing-n:127.0.0.1:61248_) [n:127.0.0.1:61248_
c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.ElectionContext
Canceling election
/collections/collection1/leader_elect/shard1/election/96642765221462034-core_node4-n_0000000003
[junit4] 2> 1468307 WARN
(zkCallback-1913-thread-2-processing-n:127.0.0.1:61248_) [n:127.0.0.1:61248_
] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to
ZK: [KeeperErrorCode = Session expired for /clusterstate.json]
[junit4] 2> 1468309 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.e.j.s.ServerConnector Stopped
ServerConnector@67af21ad{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1468309 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@339c79ba{/,null,UNAVAILABLE}
[junit4] 2> 1468313 INFO
(TEST-TestCryptoKeys.test-seed#[6780679EFCB48229]) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:35378 35378
[junit4] 2> 1468326 INFO (Thread-2664) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:35378 35378
[junit4] 2> 1468331 WARN (Thread-2664) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 7 /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> 7 /solr/clusterstate.json
[junit4] 2> 4 /solr/collections/collection1/state.json
[junit4] 2> 2 /solr/collections/.system/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 43 /solr/overseer/collection-queue-work
[junit4] 2> 37 /solr/overseer/queue
[junit4] 2> 19 /solr/overseer/queue-work
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCryptoKeys
-Dtests.method=test -Dtests.seed=6780679EFCB48229 -Dtests.slow=true
-Dtests.locale=lv-LV -Dtests.timezone=Pacific/Tongatapu -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 47.5s J1 | TestCryptoKeys.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not find
collection:.system
[junit4] > at
__randomizedtesting.SeedInfo.seed([6780679EFCB48229:EFD458445248EFD1]: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.TestCryptoKeys.test(TestCryptoKeys.java:117)
[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> 1468335 INFO
(SUITE-TestCryptoKeys-seed#[6780679EFCB48229]-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.TestCryptoKeys_6780679EFCB48229-001
[junit4] 2> Sep 23, 2016 5:26:56 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {},
docValues:{}, maxPointsInLeafNode=525, maxMBSortInHeap=6.270898947001433,
sim=ClassicSimilarity, locale=lv-LV, timezone=Pacific/Tongatapu
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_102
(64-bit)/cpus=3,threads=1,free=157489456,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestReload, TestJsonRequest,
BasicDistributedZk2Test, TestCollationFieldDocValues, TestDynamicLoading,
TestCollectionAPI, HdfsThreadLeakTest, SuggesterTest, SuggesterTSTTest,
AnalyticsMergeStrategyTest, TestReRankQParserPlugin, TestUseDocValuesAsStored,
HdfsBasicDistributedZk2Test, TestFoldingMultitermQuery,
TestFieldCacheWithThreads, TestExpandComponent, TestSortByMinMaxFunction,
TestFieldTypeResource, TestSolrDeletionPolicy2, InfoHandlerTest,
StatsComponentTest, SolrCoreCheckLockOnStartupTest,
HdfsCollectionsAPIDistributedZkTest, TestJoin, TestFieldResource,
TestSimpleTrackingShardHandler, TestScoreJoinQPNoScore, TestQuerySenderNoQuery,
TestRandomCollapseQParserPlugin, OverseerTaskQueueTest, CacheHeaderTest,
TestStandardQParsers, TestMergePolicyConfig, DeleteStatusTest, IndexSchemaTest,
TestRecovery, SpellCheckComponentTest, TestReloadDeadlock, NoCacheHeaderTest,
TestDistributedMissingSort, TestJettySolrRunner, SolrCmdDistributorTest,
TestDefaultStatsCache, ClusterStateTest, RAMDirectoryFactoryTest,
TestQuerySenderListener, PKIAuthenticationIntegrationTest, TestCoreDiscovery,
TestTestInjection, RegexBoostProcessorTest, TestSolrConfigHandlerConcurrent,
TestHashQParserPlugin, TolerantUpdateProcessorTest, VersionInfoTest,
AutoCommitTest, CursorMarkTest, TestRestManager, TestValueSourceCache,
TestManagedResource, TestConfigSetProperties, MigrateRouteKeyTest, TestDocSet,
HighlighterConfigTest, TestMissingGroups, OpenCloseCoreStressTest,
BasicFunctionalityTest, DateMathParserTest, LukeRequestHandlerTest,
CoreAdminRequestStatusTest, TestSolrDynamicMBean, TestQueryTypes,
TestLMDirichletSimilarityFactory, SparseHLLTest, TestRangeQuery, TestLockTree,
TestJmxMonitoredMap, ZkStateReaderTest, DistributedIntervalFacetingTest,
DataDrivenBlockJoinTest, TestConfig, SearchHandlerTest, OverseerRolesTest,
CustomCollectionTest, HdfsRecoverLeaseTest, TestCodecSupport, SolrXmlInZkTest,
ExplicitHLLTest, PreAnalyzedFieldTest, TestMinMaxOnMultiValuedField,
OutOfBoxZkACLAndCredentialsProvidersTest, TestUninvertingReader,
TestOmitPositions, SolrCLIZkUtilsTest, TestTolerantUpdateProcessorRandomCloud,
PreAnalyzedUpdateProcessorTest, UUIDFieldTest,
TestLeaderInitiatedRecoveryThread, BaseCdcrDistributedZkTest,
TestNumericTerms32, TestFastWriter, AliasIntegrationTest,
TestSchemaVersionResource, TestPHPSerializedResponseWriter,
BlockJoinFacetDistribTest, TestLMJelinekMercerSimilarityFactory,
ScriptEngineTest, SuggesterWFSTTest, LeaderElectionIntegrationTest,
BooleanFieldTest, TestDefaultSearchFieldResource, ShardSplitTest,
AsyncCallRequestStatusResponseTest, HdfsLockFactoryTest, TestReqParamsAPI,
TestConfigOverlay, LeaderFailoverAfterPartitionTest,
CollectionsAPIDistributedZkTest, TestOverriddenPrefixQueryForCustomFieldType,
TestReplicaProperties, TestConfigSetsAPIExclusivity, TestRandomFaceting,
UpdateRequestProcessorFactoryTest, TestFieldCacheSanityChecker,
AtomicUpdatesTest, TestFreeTextSuggestions, TestSolrQueryParser,
TestGeoJSONResponseWriter, ConvertedLegacyTest, MinimalSchemaTest,
TestCryptoKeys]
[junit4] Completed [288/636 (1!)] on J1 in 48.25s, 1 test, 1 failure <<<
FAILURES!
[...truncated 61372 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
