Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1056/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.cloud.PeerSyncReplicationTest.test
Error Message:
timeout waiting to see all nodes active
Stack Trace:
java.lang.AssertionError: timeout waiting to see all nodes active
at
__randomizedtesting.SeedInfo.seed([31A2606C446B075C:B9F65FB6EA976AA4]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.PeerSyncReplicationTest.waitTillNodesActive(PeerSyncReplicationTest.java:311)
at
org.apache.solr.cloud.PeerSyncReplicationTest.bringUpDeadNodeAndEnsureNoReplication(PeerSyncReplicationTest.java:262)
at
org.apache.solr.cloud.PeerSyncReplicationTest.forceNodeFailureAndDoPeerSync(PeerSyncReplicationTest.java:244)
at
org.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:133)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
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:811)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.update.SolrIndexMetricsTest.testIndexMetrics
Error Message:
minorMerge: 3 expected:<4> but was:<3>
Stack Trace:
java.lang.AssertionError: minorMerge: 3 expected:<4> but was:<3>
at
__randomizedtesting.SeedInfo.seed([31A2606C446B075C:FD725DD084E5FC67]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at
org.apache.solr.update.SolrIndexMetricsTest.testIndexMetrics(SolrIndexMetricsTest.java:70)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11240 lines...]
[junit4] Suite: org.apache.solr.cloud.PeerSyncReplicationTest
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.PeerSyncReplicationTest_31A2606C446B075C-001/init-core-data-001
[junit4] 2> 704711 INFO
(SUITE-PeerSyncReplicationTest-seed#[31A2606C446B075C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 704712 INFO
(SUITE-PeerSyncReplicationTest-seed#[31A2606C446B075C]-worker) [ ]
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /guhg/r
[junit4] 2> 704713 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 704713 INFO (Thread-1170) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 704714 INFO (Thread-1170) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 704813 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.ZkTestServer start zk server on port:65017
[junit4] 2> 704847 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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> 704853 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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> 704857 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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> 704861 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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> 704865 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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> 704868 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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> 704870 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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> 704874 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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> 704877 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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> 704880 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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> 704882 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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> 705071 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/control-001/cores/collection1
[junit4] 2> 705072 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 705074 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@347b52cc{/guhg/r,null,AVAILABLE}
[junit4] 2> 705077 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@aec2022{HTTP/1.1,[http/1.1]}{127.0.0.1:50339}
[junit4] 2> 705077 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.Server Started @708225ms
[junit4] 2> 705077 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/tempDir-001/control/data,
hostContext=/guhg/r, hostPort=50339,
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.PeerSyncReplicationTest_31A2606C446B075C-001/control-001/cores}
[junit4] 2> 705077 ERROR
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 705077 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 705077 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 705077 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 705077 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-01-04T18:33:05.593Z
[junit4] 2> 705084 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 705084 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/control-001/solr.xml
[junit4] 2> 705094 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65017/solr
[junit4] 2> 705149 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.OverseerElectionContext I am going to
be the leader 127.0.0.1:50339_guhg%2Fr
[junit4] 2> 705151 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.Overseer Overseer
(id=97226246404964356-127.0.0.1:50339_guhg%2Fr-n_0000000000) starting
[junit4] 2> 705164 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:50339_guhg%2Fr
[junit4] 2> 705168 INFO
(zkCallback-709-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (1)
[junit4] 2> 705367 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:50339_guhg%2Fr ] 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.PeerSyncReplicationTest_31A2606C446B075C-001/control-001/cores
[junit4] 2> 705367 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 705375 INFO
(OverseerStateUpdate-97226246404964356-127.0.0.1:50339_guhg%2Fr-n_0000000000)
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 706424 WARN
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 706425 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 706437 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 706521 WARN
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr 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> 706523 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 706542 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 706543 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection 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.PeerSyncReplicationTest_31A2606C446B075C-001/control-001/cores/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.PeerSyncReplicationTest_31A2606C446B075C-001/control-001/cores/collection1/data/]
[junit4] 2> 706543 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d46d96
[junit4] 2> 706545 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.35058328098533587]
[junit4] 2> 706554 WARN
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 706564 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 706564 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=1000 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 706565 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 706565 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 706565 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=78.8291015625,
floorSegmentMB=0.9267578125, forceMergeDeletesPctAllowed=25.385322285676374,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6030178136376037
[junit4] 2> 706566 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@72de477e[collection1] main]
[junit4] 2> 706570 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 706571 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 706571 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 706572 INFO
(searcherExecutor-1777-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr
x:collection1 c:control_collection) [n:127.0.0.1:50339_guhg%2Fr
c:control_collection x:collection1] o.a.s.c.SolrCore [collection1] Registered
new searcher Searcher@72de477e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 706572 INFO
(coreLoadExecutor-1776-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr c:control_collection x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1555619944425586688
[junit4] 2> 706583 INFO
(coreZkRegister-1769-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr
x:collection1 c:control_collection) [n:127.0.0.1:50339_guhg%2Fr
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 706583 INFO
(coreZkRegister-1769-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr
x:collection1 c:control_collection) [n:127.0.0.1:50339_guhg%2Fr
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> 706583 INFO
(coreZkRegister-1769-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr
x:collection1 c:control_collection) [n:127.0.0.1:50339_guhg%2Fr
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:50339/guhg/r/collection1/
[junit4] 2> 706583 INFO
(coreZkRegister-1769-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr
x:collection1 c:control_collection) [n:127.0.0.1:50339_guhg%2Fr
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 706584 INFO
(coreZkRegister-1769-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr
x:collection1 c:control_collection) [n:127.0.0.1:50339_guhg%2Fr
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:50339/guhg/r/collection1/ has no replicas
[junit4] 2> 706591 INFO
(coreZkRegister-1769-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr
x:collection1 c:control_collection) [n:127.0.0.1:50339_guhg%2Fr
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:50339/guhg/r/collection1/ shard1
[junit4] 2> 706743 INFO
(coreZkRegister-1769-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr
x:collection1 c:control_collection) [n:127.0.0.1:50339_guhg%2Fr
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 707029 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 707031 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:65017/solr ready
[junit4] 2> 707031 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 707031 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 707195 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-1-001/cores/collection1
[junit4] 2> 707196 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-1-001
[junit4] 2> 707196 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 707198 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1325f0d9{/guhg/r,null,AVAILABLE}
[junit4] 2> 707198 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3256f444{HTTP/1.1,[http/1.1]}{127.0.0.1:50789}
[junit4] 2> 707198 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.Server Started @710348ms
[junit4] 2> 707198 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/guhg/r, hostPort=50789,
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-1-001/cores}
[junit4] 2> 707200 ERROR
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 707200 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 707200 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 707200 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 707200 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-01-04T18:33:07.716Z
[junit4] 2> 707204 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 707204 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-1-001/solr.xml
[junit4] 2> 707211 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65017/solr
[junit4] 2> 707227 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:50789_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (1)
[junit4] 2> 707235 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:50789_guhg%2Fr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:50789_guhg%2Fr
[junit4] 2> 707237 INFO
(zkCallback-709-thread-3-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (1) -> (2)
[junit4] 2> 707239 INFO (zkCallback-713-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 707241 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (1) -> (2)
[junit4] 2> 707292 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:50789_guhg%2Fr ] 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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-1-001/cores
[junit4] 2> 707292 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:50789_guhg%2Fr ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 707297 INFO
(OverseerStateUpdate-97226246404964356-127.0.0.1:50339_guhg%2Fr-n_0000000000)
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 707401 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] 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> 708310 WARN
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 708310 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 708322 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 708383 WARN
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr 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> 708384 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 708402 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1
[junit4] 2> 708402 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-1-001/cores/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.PeerSyncReplicationTest_31A2606C446B075C-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 708402 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d46d96
[junit4] 2> 708405 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.35058328098533587]
[junit4] 2> 708420 WARN
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 708433 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 708433 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=1000
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 708434 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 708434 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 708435 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=78.8291015625,
floorSegmentMB=0.9267578125, forceMergeDeletesPctAllowed=25.385322285676374,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6030178136376037
[junit4] 2> 708436 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4ea89fc8[collection1] main]
[junit4] 2> 708438 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 708439 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 708439 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 708440 INFO
(searcherExecutor-1788-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:50789_guhg%2Fr c:collection1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4ea89fc8[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 708440 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1555619946384326656
[junit4] 2> 708451 INFO
(coreZkRegister-1782-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:50789_guhg%2Fr c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas
found to continue.
[junit4] 2> 708451 INFO
(coreZkRegister-1782-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:50789_guhg%2Fr 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> 708451 INFO
(coreZkRegister-1782-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:50789_guhg%2Fr c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:50789/guhg/r/collection1/
[junit4] 2> 708451 INFO
(coreZkRegister-1782-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:50789_guhg%2Fr c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 708451 INFO
(coreZkRegister-1782-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:50789_guhg%2Fr c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:50789/guhg/r/collection1/ has no replicas
[junit4] 2> 708457 INFO
(coreZkRegister-1782-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:50789_guhg%2Fr c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new
leader: http://127.0.0.1:50789/guhg/r/collection1/ shard1
[junit4] 2> 708583 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] 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> 708628 INFO
(coreZkRegister-1782-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:50789_guhg%2Fr c:collection1 s:shard1
r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery
necessary
[junit4] 2> 708735 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] 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> 709083 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-2-001/cores/collection1
[junit4] 2> 709084 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-2-001
[junit4] 2> 709085 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 709087 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6e5e7c38{/guhg/r,null,AVAILABLE}
[junit4] 2> 709088 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4ec36a7f{HTTP/1.1,[http/1.1]}{127.0.0.1:37612}
[junit4] 2> 709088 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.Server Started @712238ms
[junit4] 2> 709088 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/tempDir-001/jetty2,
solrconfig=solrconfig.xml, hostContext=/guhg/r, hostPort=37612,
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-2-001/cores}
[junit4] 2> 709088 ERROR
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 709089 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 709089 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 709089 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 709089 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-01-04T18:33:09.605Z
[junit4] 2> 709093 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 709093 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-2-001/solr.xml
[junit4] 2> 709102 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65017/solr
[junit4] 2> 709118 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:37612_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (2)
[junit4] 2> 709126 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:37612_guhg%2Fr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37612_guhg%2Fr
[junit4] 2> 709129 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (2) -> (3)
[junit4] 2> 709129 INFO
(zkCallback-709-thread-1-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (2) -> (3)
[junit4] 2> 709129 INFO (zkCallback-713-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 709133 INFO
(zkCallback-725-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (2) -> (3)
[junit4] 2> 709238 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] 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> 709357 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:37612_guhg%2Fr ] 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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-2-001/cores
[junit4] 2> 709357 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:37612_guhg%2Fr ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 709363 INFO
(OverseerStateUpdate-97226246404964356-127.0.0.1:50339_guhg%2Fr-n_0000000000)
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 709466 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] 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> 709466 INFO
(zkCallback-725-thread-2-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr ] 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> 710384 WARN
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 710385 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 710404 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 710560 WARN
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr 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> 710564 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 710597 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1
[junit4] 2> 710598 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 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.PeerSyncReplicationTest_31A2606C446B075C-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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 710598 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d46d96
[junit4] 2> 710602 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.35058328098533587]
[junit4] 2> 710635 WARN
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 710657 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 710657 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=1000
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 710658 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 710658 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 710660 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=78.8291015625,
floorSegmentMB=0.9267578125, forceMergeDeletesPctAllowed=25.385322285676374,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6030178136376037
[junit4] 2> 710661 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@1a667dc7[collection1] main]
[junit4] 2> 710664 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 710665 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 710665 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 710666 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1555619948718456832
[junit4] 2> 710670 INFO
(searcherExecutor-1799-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:37612_guhg%2Fr c:collection1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1a667dc7[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 710676 INFO
(coreZkRegister-1793-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:37612_guhg%2Fr c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.ZkController Core needs to
recover:collection1
[junit4] 2> 710677 INFO
(updateExecutor-722-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.DefaultSolrCoreState
Running recovery
[junit4] 2> 710678 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 710678 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
###### startupVersions=[[]]
[junit4] 2> 710678 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Begin buffering updates. core=[collection1]
[junit4] 2> 710678 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
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> 710679 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
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:50789/guhg/r/collection1/] and I am
[http://127.0.0.1:37612/guhg/r/collection1/]
[junit4] 2> 710685 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Sending prep recovery command to [http://127.0.0.1:50789/guhg/r];
[WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:37612_guhg%252Fr&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 710689 INFO (qtp1006627188-4311) [n:127.0.0.1:50789_guhg%2Fr
] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node2, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 710690 INFO (qtp1006627188-4311) [n:127.0.0.1:50789_guhg%2Fr
] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard1 of collection1) have state: recovering
[junit4] 2> 710690 INFO (qtp1006627188-4311) [n:127.0.0.1:50789_guhg%2Fr
] 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:37612_guhg%2Fr,
coreNodeName=core_node2, onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"http://127.0.0.1:37612/guhg/r","node_name":"127.0.0.1:37612_guhg%2Fr","state":"down"}
[junit4] 2> 710787 INFO
(zkCallback-725-thread-2-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr ] 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> 710789 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] 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> 711232 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-3-001/cores/collection1
[junit4] 2> 711235 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-3-001
[junit4] 2> 711247 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 711250 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6ac23789{/guhg/r,null,AVAILABLE}
[junit4] 2> 711251 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4d68b257{HTTP/1.1,[http/1.1]}{127.0.0.1:44106}
[junit4] 2> 711251 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.e.j.s.Server Started @714402ms
[junit4] 2> 711251 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/tempDir-001/jetty3,
solrconfig=solrconfig.xml, hostContext=/guhg/r, hostPort=44106,
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-3-001/cores}
[junit4] 2> 711251 ERROR
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 711277 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 711277 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 711277 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 711277 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-01-04T18:33:11.793Z
[junit4] 2> 711284 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 711285 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-3-001/solr.xml
[junit4] 2> 711322 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65017/solr
[junit4] 2> 711350 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:44106_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (0) -> (3)
[junit4] 2> 711358 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:44106_guhg%2Fr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:44106_guhg%2Fr
[junit4] 2> 711365 INFO
(zkCallback-709-thread-3-processing-n:127.0.0.1:50339_guhg%2Fr)
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (3) -> (4)
[junit4] 2> 711366 INFO
(zkCallback-725-thread-2-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (3) -> (4)
[junit4] 2> 711366 INFO (zkCallback-713-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 711366 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (3) -> (4)
[junit4] 2> 711390 INFO
(zkCallback-732-thread-2-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr ] o.a.s.c.c.ZkStateReader Updated live nodes
from ZooKeeper... (3) -> (4)
[junit4] 2> 711478 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:44106_guhg%2Fr ] 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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-3-001/cores
[junit4] 2> 711478 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C])
[n:127.0.0.1:44106_guhg%2Fr ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 711552 INFO
(zkCallback-725-thread-2-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr ] 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> 711553 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] 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> 711553 INFO
(zkCallback-732-thread-2-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr ] 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> 711556 INFO
(OverseerStateUpdate-97226246404964356-127.0.0.1:50339_guhg%2Fr-n_0000000000)
[n:127.0.0.1:50339_guhg%2Fr ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 711660 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] 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> 711661 INFO
(zkCallback-732-thread-2-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr ] 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> 711682 INFO
(zkCallback-725-thread-2-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr ] 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> 711691 INFO (qtp1006627188-4311) [n:127.0.0.1:50789_guhg%2Fr
] 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:37612_guhg%2Fr,
coreNodeName=core_node2, onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"http://127.0.0.1:37612/guhg/r","node_name":"127.0.0.1:37612_guhg%2Fr","state":"recovering"}
[junit4] 2> 711691 INFO (qtp1006627188-4311) [n:127.0.0.1:50789_guhg%2Fr
] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node2, state:
recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 711691 INFO (qtp1006627188-4311) [n:127.0.0.1:50789_guhg%2Fr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:37612_guhg%252Fr&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> 712496 WARN
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 712497 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 712509 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 712574 WARN
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr 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> 712576 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 712598 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection collection1
[junit4] 2> 712598 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-3-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.PeerSyncReplicationTest_31A2606C446B075C-001/shard-3-001/cores/collection1/data/]
[junit4] 2> 712599 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@32d46d96
[junit4] 2> 712601 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.35058328098533587]
[junit4] 2> 712607 WARN
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 712620 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 712620 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=1000
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 712621 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 712621 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 712621 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=13, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=78.8291015625,
floorSegmentMB=0.9267578125, forceMergeDeletesPctAllowed=25.385322285676374,
segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6030178136376037
[junit4] 2> 712622 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@e202a21[collection1] main]
[junit4] 2> 712624 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 712625 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 712625 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 712626 INFO
(searcherExecutor-1810-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:44106_guhg%2Fr c:collection1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@e202a21[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 712626 INFO
(coreLoadExecutor-1809-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr c:collection1 x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1555619950773665792
[junit4] 2> 712633 INFO
(coreZkRegister-1804-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 c:collection1) [n:127.0.0.1:44106_guhg%2Fr c:collection1 s:shard1
r:core_node3 x:collection1] o.a.s.c.ZkController Core needs to
recover:collection1
[junit4] 2> 712633 INFO
(updateExecutor-729-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.u.DefaultSolrCoreState
Running recovery
[junit4] 2> 712634 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 712635 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
###### startupVersions=[[]]
[junit4] 2> 712635 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
Begin buffering updates. core=[collection1]
[junit4] 2> 712635 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.u.UpdateLog Starting
to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 712635 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
Publishing state of core [collection1] as recovering, leader is
[http://127.0.0.1:50789/guhg/r/collection1/] and I am
[http://127.0.0.1:44106/guhg/r/collection1/]
[junit4] 2> 712639 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
Sending prep recovery command to [http://127.0.0.1:50789/guhg/r];
[WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:44106_guhg%252Fr&coreNodeName=core_node3&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 712641 INFO (qtp1006627188-4313) [n:127.0.0.1:50789_guhg%2Fr
] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 712641 INFO (qtp1006627188-4313) [n:127.0.0.1:50789_guhg%2Fr
] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard1 of collection1) have state: recovering
[junit4] 2> 712641 INFO (qtp1006627188-4313) [n:127.0.0.1:50789_guhg%2Fr
] 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:44106_guhg%2Fr,
coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:44106/guhg/r","node_name":"127.0.0.1:44106_guhg%2Fr","state":"down"}
[junit4] 2> 712692 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 712692 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Wait for recoveries to finish - wait 30
for each attempt
[junit4] 2> 712692 INFO
(TEST-PeerSyncReplicationTest.test-seed#[31A2606C446B075C]) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1 failOnTimeout:true timeout (sec):30
[junit4] 2> 712743 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] 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> 712743 INFO
(zkCallback-732-thread-2-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr ] 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> 712743 INFO
(zkCallback-725-thread-2-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr ] 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> 713645 INFO (qtp1006627188-4313) [n:127.0.0.1:50789_guhg%2Fr
] 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:44106_guhg%2Fr,
coreNodeName=core_node3, onlyIfActiveCheckResult=false, nodeProps:
core_node3:{"core":"collection1","base_url":"http://127.0.0.1:44106/guhg/r","node_name":"127.0.0.1:44106_guhg%2Fr","state":"recovering"}
[junit4] 2> 713645 INFO (qtp1006627188-4313) [n:127.0.0.1:50789_guhg%2Fr
] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state:
recovering, checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 713645 INFO (qtp1006627188-4313) [n:127.0.0.1:50789_guhg%2Fr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:44106_guhg%252Fr&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node3&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1005
[junit4] 2> 718693 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Attempting to PeerSync from [http://127.0.0.1:50789/guhg/r/collection1/] -
recoveringAfterStartup=[true]
[junit4] 2> 718693 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.PeerSync PeerSync:
core=collection1 url=http://127.0.0.1:37612/guhg/r START
replicas=[http://127.0.0.1:50789/guhg/r/collection1/] nUpdates=1000
[junit4] 2> 718697 INFO (qtp1006627188-4312) [n:127.0.0.1:50789_guhg%2Fr
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.u.IndexFingerprint
IndexFingerprint millis:1.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 718697 INFO (qtp1006627188-4312) [n:127.0.0.1:50789_guhg%2Fr
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.c.S.Request
[collection1] webapp=/guhg/r path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 718698 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.IndexFingerprint
IndexFingerprint millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 718698 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.PeerSync We are
already in sync. No need to do a PeerSync
[junit4] 2> 718698 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2
start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 718699 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2
No uncommitted changes. Skipping IW.commit.
[junit4] 2> 718699 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 718699 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
PeerSync stage of recovery was successful.
[junit4] 2> 718699 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Replaying updates buffered during PeerSync.
[junit4] 2> 718699 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy No
replay needed.
[junit4] 2> 718700 INFO
(recoveryExecutor-723-thread-1-processing-n:127.0.0.1:37612_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:37612_guhg%2Fr
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Registering as Active after recovery.
[junit4] 2> 718705 INFO
(zkCallback-732-thread-2-processing-n:127.0.0.1:44106_guhg%2Fr)
[n:127.0.0.1:44106_guhg%2Fr ] 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> 718705 INFO
(zkCallback-725-thread-2-processing-n:127.0.0.1:37612_guhg%2Fr)
[n:127.0.0.1:37612_guhg%2Fr ] 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> 718705 INFO
(zkCallback-719-thread-1-processing-n:127.0.0.1:50789_guhg%2Fr)
[n:127.0.0.1:50789_guhg%2Fr ] 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> 720652 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.c.RecoveryStrategy
Attempting to PeerSync from [http://127.0.0.1:50789/guhg/r/collection1/] -
recoveringAfterStartup=[true]
[junit4] 2> 720652 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.u.PeerSync PeerSync:
core=collection1 url=http://127.0.0.1:44106/guhg/r START
replicas=[http://127.0.0.1:50789/guhg/r/collection1/] nUpdates=1000
[junit4] 2> 720655 INFO (qtp1006627188-4315) [n:127.0.0.1:50789_guhg%2Fr
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.u.IndexFingerprint
IndexFingerprint millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 720655 INFO (qtp1006627188-4315) [n:127.0.0.1:50789_guhg%2Fr
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.c.S.Request
[collection1] webapp=/guhg/r path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 720657 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.u.IndexFingerprint
IndexFingerprint millis:1.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 720657 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.u.PeerSync We are
already in sync. No need to do a PeerSync
[junit4] 2> 720657 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2
start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 720657 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2
No uncommitted changes. Skipping IW.commit.
[junit4] 2> 720657 INFO
(recoveryExecutor-730-thread-1-processing-n:127.0.0.1:44106_guhg%2Fr
x:collection1 s:shard1 c:collection1 r:core_node3) [n:127.0.0.1:44106_guhg%2Fr
c:collection1 s:shard1 r:core_node3 x:collection1] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 720657 INFO (recoveryExec
[...truncated too long message...]
215ce[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C300)
Uninverting(_3(7.0.0):C100) Uninverting(_5(7.0.0):C100)
Uninverting(_6(7.0.0):C100) Uninverting(_7(7.0.0):C100)
Uninverting(_9(7.0.0):C100) Uninverting(_a(7.0.0):C100)
Uninverting(_b(7.0.0):C100)))}
[junit4] 2> 916625 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 916626 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib,
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 916682 WARN
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 916682 WARN
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergeFactor> is deprecated, configure
it on the relevant <mergePolicyFactory> instead.
[junit4] 2> 916682 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 916695 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 916757 WARN
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 916759 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 916767 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [ ]
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from
instancedir
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1
[junit4] 2> 916768 WARN
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was
configured, using first MBeanServer.
[junit4] 2> 916776 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@32d46d96
[junit4] 2> 916777 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.m.SolrMetricManager Replacing existing reporter 'default'
in registry 'solr.core.collection1':
[org.apache.solr.metrics.reporters.SolrJmxReporter@5528bb78: domain =
solr.core.collection1, service url = null, agent id = null]
[junit4] 2> 916779 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.update.SolrIndexMetricsTest_31A2606C446B075C-001/init-core-data-001/]
[junit4] 2> 916824 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 916824 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 916825 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@531c246a[collection1] main]
[junit4] 2> 916825 WARN
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 916825 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 916837 INFO
(searcherExecutor-1900-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@531c246a[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C300)
Uninverting(_3(7.0.0):C100) Uninverting(_5(7.0.0):C100)
Uninverting(_6(7.0.0):C100) Uninverting(_7(7.0.0):C100)
Uninverting(_9(7.0.0):C100) Uninverting(_a(7.0.0):C100)
Uninverting(_b(7.0.0):C100)))}
[junit4] 2> 917022 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 917069 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@3401f0c6[collection1] main]
[junit4] 2> 917069 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@3edce3e0
[junit4] 2> 917070 INFO
(searcherExecutor-1900-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3401f0c6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C300)
Uninverting(_8(7.0.0):C300) Uninverting(_c(7.0.0):C300)
Uninverting(_b(7.0.0):C100)))}
[junit4] 2> 917070 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1
[junit4] 2> 917075 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[31A2606C446B075C]) [
x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testIndexMetrics
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=SolrIndexMetricsTest -Dtests.method=testIndexMetrics
-Dtests.seed=31A2606C446B075C -Dtests.slow=true -Dtests.locale=zh-HK
-Dtests.timezone=America/Belem -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 1.19s J1 | SolrIndexMetricsTest.testIndexMetrics <<<
[junit4] > Throwable #1: java.lang.AssertionError: minorMerge: 3
expected:<4> but was:<3>
[junit4] > at
__randomizedtesting.SeedInfo.seed([31A2606C446B075C:FD725DD084E5FC67]:0)
[junit4] > at
org.apache.solr.update.SolrIndexMetricsTest.testIndexMetrics(SolrIndexMetricsTest.java:70)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 917076 INFO
(SUITE-SolrIndexMetricsTest-seed#[31A2606C446B075C]-worker) [ x:collection1]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 917076 INFO
(SUITE-SolrIndexMetricsTest-seed#[31A2606C446B075C]-worker) [ x:collection1]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=333094227
[junit4] 2> 917076 INFO
(coreCloseExecutor-1904-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@4e4b911e
[junit4] 2> 917078 INFO
(coreCloseExecutor-1904-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 917078 INFO
(SUITE-SolrIndexMetricsTest-seed#[31A2606C446B075C]-worker) [ x:collection1]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[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.update.SolrIndexMetricsTest_31A2606C446B075C-001
[junit4] 2> NOTE: test params are:
codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION,
chunkSize=15442, maxDocsPerChunk=610, blockSize=123),
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION,
chunkSize=15442, blockSize=123)), sim=RandomSimilarity(queryNorm=false): {},
locale=zh-HK, timezone=America/Belem
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_112
(64-bit)/cpus=3,threads=1,free=191261632,total=536870912
[junit4] 2> NOTE: All tests run in this JVM:
[TestRandomCollapseQParserPlugin, CurrencyFieldOpenExchangeTest,
CleanupOldIndexTest, SpellCheckCollatorTest, TestAnalyzedSuggestions,
LeaderElectionIntegrationTest, TestCorePropertiesReload,
SimpleCollectionCreateDeleteTest, HttpSolrCallGetCoreTest, TestMissingGroups,
CircularListTest, TestOrdValues, DataDrivenBlockJoinTest, TestCodecSupport,
BasicZkTest, TestTrie, StatsComponentTest, DistributedExpandComponentTest,
TestFilteredDocIdSet, TestSolrConfigHandlerConcurrent, StressHdfsTest,
TestDynamicFieldResource, ExitableDirectoryReaderTest, TermVectorComponentTest,
ShufflingReplicaListTransformerTest, CdcrReplicationHandlerTest,
TestBulkSchemaConcurrent, DistributedVersionInfoTest, SuggesterTSTTest,
LoggingHandlerTest, TestMiniSolrCloudCluster, PreAnalyzedUpdateProcessorTest,
TestConfig, TestMacroExpander, HdfsBasicDistributedZk2Test,
TestFieldCacheWithThreads, TestDynamicLoading, ZkSolrClientTest,
TestOmitPositions, TestSolrDynamicMBean, TestBinaryResponseWriter,
DistribCursorPagingTest, PeerSyncReplicationTest, TestLuceneMatchVersion,
TestCustomSort, SuggesterWFSTTest, SolrIndexMetricsTest]
[junit4] Completed [198/675 (2!)] on J1 in 1.53s, 1 test, 1 failure <<<
FAILURES!
[...truncated 63988 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
