Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Solaris/555/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED:
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail
Error Message:
expected:<200> but was:<404>
Stack Trace:
java.lang.AssertionError: expected:<200> but was:<404>
at
__randomizedtesting.SeedInfo.seed([AF8E0FECB28DF132:C7313AC66217E3DE]: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.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.cancelDelegationToken(TestSolrCloudWithDelegationTokens.java:141)
at
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:295)
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)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsRecoveryZkTest
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at
org.apache.solr.update.HdfsTransactionLog.<init>(HdfsTransactionLog.java:130)
at org.apache.solr.update.HdfsUpdateLog.init(HdfsUpdateLog.java:202) at
org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:137) at
org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:94) at
org.apache.solr.update.DirectUpdateHandler2.<init>(DirectUpdateHandler2.java:102)
at sun.reflect.GeneratedConstructorAccessor190.newInstance(Unknown Source)
at
sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:423) at
org.apache.solr.core.SolrCore.createInstance(SolrCore.java:704) at
org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:766) at
org.apache.solr.core.SolrCore.initUpdateHandler(SolrCore.java:1005) at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:870) at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:774) at
org.apache.solr.core.CoreContainer.create(CoreContainer.java:842) at
org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498) at
java.util.concurrent.FutureTask.run(FutureTask.java:266) at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not
released!!! [HdfsTransactionLog]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at
org.apache.solr.update.HdfsTransactionLog.<init>(HdfsTransactionLog.java:130)
at org.apache.solr.update.HdfsUpdateLog.init(HdfsUpdateLog.java:202)
at org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:137)
at org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:94)
at
org.apache.solr.update.DirectUpdateHandler2.<init>(DirectUpdateHandler2.java:102)
at sun.reflect.GeneratedConstructorAccessor190.newInstance(Unknown
Source)
at
sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:704)
at org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:766)
at org.apache.solr.core.SolrCore.initUpdateHandler(SolrCore.java:1005)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:870)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
at
org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([AF8E0FECB28DF132]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:266)
at sun.reflect.GeneratedMethodAccessor21.invoke(Unknown Source)
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$7.evaluate(RandomizedRunner.java:870)
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 11243 lines...]
[junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_AF8E0FECB28DF132-001/init-core-data-001
[junit4] 2> 783062 INFO
(SUITE-TestSolrCloudWithDelegationTokens-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 783066 INFO
(SUITE-TestSolrCloudWithDelegationTokens-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_AF8E0FECB28DF132-001/tempDir-001
[junit4] 2> 783066 INFO
(SUITE-TestSolrCloudWithDelegationTokens-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 783066 INFO (Thread-6465) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 783066 INFO (Thread-6465) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 783167 INFO
(SUITE-TestSolrCloudWithDelegationTokens-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:40534
[junit4] 2> 783177 INFO (jetty-launcher-1271-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 783177 INFO (jetty-launcher-1271-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 783179 INFO (jetty-launcher-1271-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@54a9b394{/solr,null,AVAILABLE}
[junit4] 2> 783180 INFO (jetty-launcher-1271-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@39aaa939{HTTP/1.1,[http/1.1]}{127.0.0.1:64360}
[junit4] 2> 783180 INFO (jetty-launcher-1271-thread-1) [ ]
o.e.j.s.Server Started @786288ms
[junit4] 2> 783180 INFO (jetty-launcher-1271-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=64360}
[junit4] 2> 783181 ERROR (jetty-launcher-1271-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 783181 INFO (jetty-launcher-1271-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@38dca9c2{/solr,null,AVAILABLE}
[junit4] 2> 783181 INFO (jetty-launcher-1271-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.4.0
[junit4] 2> 783181 INFO (jetty-launcher-1271-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 783181 INFO (jetty-launcher-1271-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 783181 INFO (jetty-launcher-1271-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-12-15T07:41:48.655Z
[junit4] 2> 783181 INFO (jetty-launcher-1271-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@2fffb40c{HTTP/1.1,[http/1.1]}{127.0.0.1:47838}
[junit4] 2> 783181 INFO (jetty-launcher-1271-thread-2) [ ]
o.e.j.s.Server Started @786289ms
[junit4] 2> 783181 INFO (jetty-launcher-1271-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=47838}
[junit4] 2> 783181 ERROR (jetty-launcher-1271-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 783182 INFO (jetty-launcher-1271-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.4.0
[junit4] 2> 783182 INFO (jetty-launcher-1271-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 783182 INFO (jetty-launcher-1271-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 783182 INFO (jetty-launcher-1271-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-12-15T07:41:48.656Z
[junit4] 2> 783185 INFO (jetty-launcher-1271-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 783185 INFO (jetty-launcher-1271-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 783194 INFO (jetty-launcher-1271-thread-2) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 783194 INFO (jetty-launcher-1271-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 783195 INFO (jetty-launcher-1271-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40534/solr
[junit4] 2> 783196 INFO (jetty-launcher-1271-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40534/solr
[junit4] 2> 783238 INFO (jetty-launcher-1271-thread-1)
[n:127.0.0.1:64360_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:64360_solr
[junit4] 2> 783239 INFO (jetty-launcher-1271-thread-2)
[n:127.0.0.1:47838_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:47838_solr
[junit4] 2> 783239 INFO (jetty-launcher-1271-thread-1)
[n:127.0.0.1:64360_solr ] o.a.s.c.Overseer Overseer
(id=97110439267139590-127.0.0.1:64360_solr-n_0000000000) starting
[junit4] 2> 783242 INFO
(zkCallback-1282-thread-1-processing-n:127.0.0.1:64360_solr)
[n:127.0.0.1:64360_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 783253 INFO (jetty-launcher-1271-thread-1)
[n:127.0.0.1:64360_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:64360_solr
[junit4] 2> 783254 INFO
(zkCallback-1281-thread-1-processing-n:127.0.0.1:47838_solr)
[n:127.0.0.1:47838_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 783256 INFO
(zkCallback-1281-thread-1-processing-n:127.0.0.1:47838_solr)
[n:127.0.0.1:47838_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 783258 INFO
(OverseerStateUpdate-97110439267139590-127.0.0.1:64360_solr-n_0000000000)
[n:127.0.0.1:64360_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 783333 INFO (jetty-launcher-1271-thread-2)
[n:127.0.0.1:47838_solr ] o.a.s.c.CoreContainer Authentication plugin class
obtained from system property 'authenticationPlugin':
org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 783346 INFO (jetty-launcher-1271-thread-2)
[n:127.0.0.1:47838_solr ] o.a.s.s.KerberosPlugin Params: {token.valid=30,
delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, cookie.path=/,
zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm,
token.validity=36000, signer.secret.provider.zookeeper.path=/token,
type=kerberos, zk-dt-secret-manager.enable=true, kerberos.name.rules=DEFAULT,
signer.secret.provider=zookeeper}
[junit4] 2> 783451 INFO (jetty-launcher-1271-thread-2)
[n:127.0.0.1:47838_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 783451 INFO (jetty-launcher-1271-thread-1)
[n:127.0.0.1:64360_solr ] o.a.s.c.CoreContainer Authentication plugin class
obtained from system property 'authenticationPlugin':
org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 783451 INFO (jetty-launcher-1271-thread-1)
[n:127.0.0.1:64360_solr ] o.a.s.s.KerberosPlugin Params: {token.valid=30,
delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, cookie.path=/,
zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm,
token.validity=36000, signer.secret.provider.zookeeper.path=/token,
type=kerberos, zk-dt-secret-manager.enable=true, kerberos.name.rules=DEFAULT,
signer.secret.provider=zookeeper}
[junit4] 2> 783452 INFO (jetty-launcher-1271-thread-1)
[n:127.0.0.1:64360_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 783485 INFO (jetty-launcher-1271-thread-1-EventThread)
[n:127.0.0.1:64360_solr ] o.a.c.f.s.ConnectionStateManager State change:
CONNECTED
[junit4] 2> 783487 INFO (jetty-launcher-1271-thread-2-EventThread)
[n:127.0.0.1:47838_solr ] o.a.c.f.s.ConnectionStateManager State change:
CONNECTED
[junit4] 2> 783635 INFO (jetty-launcher-1271-thread-2)
[n:127.0.0.1:47838_solr ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring
the default client with:
org.apache.solr.security.HttpParamDelegationTokenPlugin$2@1ac9276e
[junit4] 2> 783635 INFO (jetty-launcher-1271-thread-2)
[n:127.0.0.1:47838_solr ] o.a.s.u.UpdateShardHandler Reconfiguring the
default client with:
org.apache.solr.security.HttpParamDelegationTokenPlugin$2@1ac9276e
[junit4] 2> 783636 INFO (jetty-launcher-1271-thread-1)
[n:127.0.0.1:64360_solr ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring
the default client with:
org.apache.solr.security.HttpParamDelegationTokenPlugin$2@2313bd49
[junit4] 2> 783636 INFO (jetty-launcher-1271-thread-2)
[n:127.0.0.1:47838_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_AF8E0FECB28DF132-001/tempDir-001/node2/.
[junit4] 2> 783636 INFO (jetty-launcher-1271-thread-1)
[n:127.0.0.1:64360_solr ] o.a.s.u.UpdateShardHandler Reconfiguring the
default client with:
org.apache.solr.security.HttpParamDelegationTokenPlugin$2@2313bd49
[junit4] 2> 783639 INFO (jetty-launcher-1271-thread-1)
[n:127.0.0.1:64360_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_AF8E0FECB28DF132-001/tempDir-001/node1/.
[junit4] 2> 783686 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify
[junit4] 2> 783756 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 783758 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 783773 INFO (qtp370379419-12138) [n:127.0.0.1:47838_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhb9igFZJXqa_QEDFMILYURSoSY7Azqs-eJhu4dDi13qB3NvbHItZHQA&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 783773 INFO (qtp370379419-12138) [n:127.0.0.1:47838_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhb9igFZJXqa_QEDFMILYURSoSY7Azqs-eJhu4dDi13qB3NvbHItZHQA&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 783779 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 783780 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 783787 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 783793 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 783794 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 783796 INFO (qtp370379419-12139) [n:127.0.0.1:47838_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhb9igFZJXqa_QEDFMILYURSoSY7Azqs-eJhu4dDi13qB3NvbHItZHQA&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 783796 INFO (qtp370379419-12139) [n:127.0.0.1:47838_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhb9igFZJXqa_QEDFMILYURSoSY7Azqs-eJhu4dDi13qB3NvbHItZHQA&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 783801 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 783802 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify
[junit4] 2> 783828 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient
[junit4] 2> 783845 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 783847 INFO (qtp370379419-12140) [n:127.0.0.1:47838_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhd7igFZJXqbewIDFC9ayVxYD7qdXyGz61-G9llfM7urB3NvbHItZHQA&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 783847 INFO (qtp370379419-12140) [n:127.0.0.1:47838_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhd7igFZJXqbewIDFC9ayVxYD7qdXyGz61-G9llfM7urB3NvbHItZHQA&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 783848 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient
[junit4] 2> 783881 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
[junit4] 2> 783907 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestSolrCloudWithDelegationTokens
-Dtests.method=testDelegationTokenCancelFail -Dtests.seed=AF8E0FECB28DF132
-Dtests.slow=true -Dtests.locale=cs -Dtests.timezone=Asia/Ujung_Pandang
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.06s J1 |
TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<200> but
was:<404>
[junit4] > at
__randomizedtesting.SeedInfo.seed([AF8E0FECB28DF132:C7313AC66217E3DE]:0)
[junit4] > at
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.cancelDelegationToken(TestSolrCloudWithDelegationTokens.java:141)
[junit4] > at
org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:295)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 783939 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail
[junit4] 2> 783949 WARN (qtp370379419-12136) [ ]
o.a.h.s.a.s.AuthenticationFilter Authentication exception:
org.apache.hadoop.security.AccessControlException: foo tries to renew a token
with renewer bar
[junit4] 2> 783949 WARN (qtp370379419-12136) [ ]
o.a.s.s.KerberosPlugin Could not find
org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 783949 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 783952 WARN (qtp1826947984-12130) [ ]
o.a.h.s.a.s.AuthenticationFilter Authentication exception:
org.apache.hadoop.security.AccessControlException: foo tries to renew a token
with renewer bar
[junit4] 2> 783952 WARN (qtp1826947984-12130) [ ]
o.a.s.s.KerberosPlugin Could not find
org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 783952 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 783961 WARN (qtp370379419-12131) [ ]
o.a.h.s.a.s.AuthenticationFilter Authentication exception:
org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew
a token with renewer renewUser
[junit4] 2> 783961 WARN (qtp370379419-12131) [ ]
o.a.s.s.KerberosPlugin Could not find
org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 783961 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 783963 WARN (qtp1826947984-12123) [ ]
o.a.h.s.a.s.AuthenticationFilter Authentication exception:
org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew
a token with renewer renewUser
[junit4] 2> 783963 WARN (qtp1826947984-12123) [ ]
o.a.s.s.KerberosPlugin Could not find
org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 783964 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 783964 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail
[junit4] 2> 783993 INFO
(TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths
[junit4] 2> 784002 INFO
(TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths
[junit4] 2> 784038 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew
[junit4] 2> 784055 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784057 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784059 INFO (qtp370379419-12140) [n:127.0.0.1:47838_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhhLigFZJXqcSwcDFOW8w-wuFbVC41BhIQRb4szFeD30B3NvbHItZHQA&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 784059 INFO (qtp370379419-12140) [n:127.0.0.1:47838_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhhLigFZJXqcSwcDFOW8w-wuFbVC41BhIQRb4szFeD30B3NvbHItZHQA&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 784065 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784067 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784069 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784075 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784077 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784080 INFO (qtp370379419-12140) [n:127.0.0.1:47838_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhhLigFZJXqcSwcDFOW8w-wuFbVC41BhIQRb4szFeD30B3NvbHItZHQA&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 784080 INFO (qtp370379419-12140) [n:127.0.0.1:47838_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhhLigFZJXqcSwcDFOW8w-wuFbVC41BhIQRb4szFeD30B3NvbHItZHQA&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 784085 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784086 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784089 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784108 INFO (qtp370379419-12140) [n:127.0.0.1:47838_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhh-igFZJXqcfggDFJyndTWO5spVmK2Nqhq2XIQnPgJ2B3NvbHItZHQA&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 784108 INFO (qtp370379419-12140) [n:127.0.0.1:47838_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhh-igFZJXqcfggDFJyndTWO5spVmK2Nqhq2XIQnPgJ2B3NvbHItZHQA&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 784110 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784113 INFO (qtp1826947984-12128) [n:127.0.0.1:64360_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhh-igFZJXqcfggDFJyndTWO5spVmK2Nqhq2XIQnPgJ2B3NvbHItZHQA&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 784113 INFO (qtp1826947984-12128) [n:127.0.0.1:64360_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhh-igFZJXqcfggDFJyndTWO5spVmK2Nqhq2XIQnPgJ2B3NvbHItZHQA&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 784117 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784119 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784121 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784135 INFO (qtp370379419-12139) [n:127.0.0.1:47838_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhieigFZJXqcngkDFPLBvbuukI42hhB-dqZ3PskWFKj1B3NvbHItZHQA&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 784136 INFO (qtp370379419-12139) [n:127.0.0.1:47838_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhieigFZJXqcngkDFPLBvbuukI42hhB-dqZ3PskWFKj1B3NvbHItZHQA&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 784138 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784142 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784143 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784145 INFO (qtp370379419-12139) [n:127.0.0.1:47838_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhieigFZJXqcngkDFPLBvbuukI42hhB-dqZ3PskWFKj1B3NvbHItZHQA&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 784145 INFO (qtp370379419-12139) [n:127.0.0.1:47838_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhieigFZJXqcngkDFPLBvbuukI42hhB-dqZ3PskWFKj1B3NvbHItZHQA&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 784149 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784163 INFO (qtp370379419-12138) [n:127.0.0.1:47838_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhi5igFZJXqcuQoDFH9Frvj_xagNZ-h4GxyEyU5uojI4B3NvbHItZHQA&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 784163 INFO (qtp370379419-12138) [n:127.0.0.1:47838_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhi5igFZJXqcuQoDFH9Frvj_xagNZ-h4GxyEyU5uojI4B3NvbHItZHQA&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 784168 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784169 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784172 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784177 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784178 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784180 INFO (qtp370379419-12137) [n:127.0.0.1:47838_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhi5igFZJXqcuQoDFH9Frvj_xagNZ-h4GxyEyU5uojI4B3NvbHItZHQA&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 784180 INFO (qtp370379419-12137) [n:127.0.0.1:47838_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=LIST&delegation=GgADYmFyA2JhcgCKAVkBbhi5igFZJXqcuQoDFH9Frvj_xagNZ-h4GxyEyU5uojI4B3NvbHItZHQA&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 784184 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784184 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew
[junit4] 2> 784215 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel
[junit4] 2> 784228 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784229 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784232 ERROR (qtp370379419-12136) [ ]
o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path
[/ZKDTSMTokensRoot/DT_11]
[junit4] 2> 784244 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784245 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784247 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784250 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784265 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784267 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784269 ERROR (qtp370379419-12137) [n:127.0.0.1:47838_solr ]
o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path
[/ZKDTSMTokensRoot/DT_12]
[junit4] 2> 784276 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784278 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784280 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784284 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= t, 01-Led-1970 00:00:00 GMT;
HttpOnly". Invalid 'expires' attribute: t, 01-Led-1970 00:00:00 GMT
[junit4] 2> 784284 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel
[junit4] 2> 784310 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp
[junit4] 2> 784314 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 784316 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[AF8E0FECB28DF132])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:40534/solr ready
[junit4] 2> 784318 INFO (qtp370379419-12131) [n:127.0.0.1:47838_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
op=someSolrOperation&action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 784318 INFO (qtp370379419-12131) [n:127.0.0.1:47838_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={op=someSolrOperation&action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 784319 WARN
(TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[AF8E0FECB28DF132])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=bar&p=bar&t=test&e=1481823709792&s=1mUU6CTn2bDJNuYcZPELvROz9YI=";
Path=/; Domain=127.0.0.1; Expires= t, 15-Pro-2016 17:41:49 GMT; HttpOnly".
Invalid 'expires' attribute: t, 15-Pro-2016 17:41:49 GMT
[junit4] 2> 784320 INFO
(TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[AF8E0FECB28DF132])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp
[junit4] 2> 784321 INFO (jetty-launcher-1271-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@39aaa939{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 784322 INFO (jetty-launcher-1271-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1371183145
[junit4] 2> 784323 INFO (jetty-launcher-1271-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@2fffb40c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 784323 INFO (jetty-launcher-1271-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1352799876
[junit4] 2> 784333 INFO
(zkCallback-1282-thread-3-processing-n:127.0.0.1:64360_solr)
[n:127.0.0.1:64360_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (1)
[junit4] 2> 784334 WARN
(zkCallback-1281-thread-1-processing-n:127.0.0.1:47838_solr)
[n:127.0.0.1:47838_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 784334 INFO (jetty-launcher-1271-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=97110439267139590-127.0.0.1:64360_solr-n_0000000000) closing
[junit4] 2> 784335 INFO
(OverseerStateUpdate-97110439267139590-127.0.0.1:64360_solr-n_0000000000)
[n:127.0.0.1:64360_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:64360_solr
[junit4] 2> 784333 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x159016e14800005, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 784337 ERROR
(Thread[Thread-6482,5,TGRP-TestSolrCloudWithDelegationTokens])
[n:127.0.0.1:47838_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager
ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 784339 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x159016e14800006, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 784340 ERROR
(Thread[Thread-6483,5,TGRP-TestSolrCloudWithDelegationTokens])
[n:127.0.0.1:64360_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager
ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 784352 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x159016e14800007, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 784352 INFO (jetty-launcher-1271-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@38dca9c2{/solr,null,UNAVAILABLE}
[junit4] 2> 784352 INFO (jetty-launcher-1271-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@54a9b394{/solr,null,UNAVAILABLE}
[junit4] 2> 784356 INFO
(SUITE-TestSolrCloudWithDelegationTokens-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:40534 40534
[junit4] 2> 784370 INFO (Thread-6465) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:40534 40534
[junit4] 2> 784372 WARN (Thread-6465) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/aliases.json
[junit4] 2> 18 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/clusterstate.json
[junit4] 2> 4 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_7
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_6
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_9
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_8
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_1
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_3
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_3
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_2
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_2
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_5
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_4
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_12
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_11
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_10
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/live_nodes
[junit4] 2> 18 /solr/collections
[junit4] 2> 3 /solr/overseer/queue
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2> 2
/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
[junit4] 2>
[junit4] 2> 784373 INFO
(SUITE-TestSolrCloudWithDelegationTokens-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithDelegationTokens_AF8E0FECB28DF132-001
[junit4] 2> Dec 15, 2016 7:41:49 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 7 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard,
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=cs,
timezone=Asia/Ujung_Pandang
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_112
(64-bit)/cpus=3,threads=1,free=147571624,total=461373440
[junit4] 2> NOTE: All tests run in this JVM:
[FieldMutatingUpdateProcessorTest, TestQueryTypes,
ParsingFieldUpdateProcessorsTest, TestIndexingPerformance,
TestSSLRandomization, TestNRTOpen, SolrCloudExampleTest, RequestLoggingTest,
AddSchemaFieldsUpdateProcessorFactoryTest, TestCustomSort,
TestDFISimilarityFactory, NotRequiredUniqueKeyTest, AliasIntegrationTest,
OverriddenZkACLAndCredentialsProvidersTest, TestWriterPerf,
TestFilteredDocIdSet, DirectUpdateHandlerOptimizeTest,
TestAuthenticationFramework, URLClassifyProcessorTest, TestConfigOverlay,
CacheHeaderTest, LoggingHandlerTest, TestSortByMinMaxFunction,
TestClusterProperties, TestHashQParserPlugin, TestCoreDiscovery,
TestConfigSetProperties, TestQuerySenderListener, TestQuerySenderNoQuery,
DistribCursorPagingTest, TestSolrCloudSnapshots, CheckHdfsIndexTest,
ChaosMonkeySafeLeaderTest, TestRangeQuery, ExternalFileFieldSortTest,
CurrencyFieldOpenExchangeTest, TestSuggestSpellingConverter, EchoParamsTest,
ClusterStateTest, TestCorePropertiesReload, BlockCacheTest,
TestShardHandlerFactory, TestFuzzyAnalyzedSuggestions, TestRTimerTree,
CursorMarkTest, TestInfoStreamLogging, SuggesterTest,
TestSimpleTrackingShardHandler, TestConfigSetsAPIZkFailure,
DataDrivenBlockJoinTest, TestRandomFlRTGCloud, ShardRoutingCustomTest,
TestMaxScoreQueryParser, DistribJoinFromCollectionTest,
TestAtomicUpdateErrorCases, TestPerFieldSimilarityClassic, TestHdfsUpdateLog,
TestDocSet, TestRestoreCore, TestCloudInspectUtil, DeleteInactiveReplicaTest,
BasicDistributedZk2Test, TestEmbeddedSolrServerConstructors,
CdcrReplicationHandlerTest, TestNumericTerms32, TestCloudSchemaless,
TestConfigSetsAPI, DeleteNodeTest, FieldAnalysisRequestHandlerTest,
TestRequestForwarding, CoreSorterTest, TestSchemaSimilarityResource,
ForceLeaderTest, NoCacheHeaderTest, TestFieldCacheWithThreads,
TestSearcherReuse, TestStressUserVersions, ChangedSchemaMergeTest,
TestCrossCoreJoin, TestPHPSerializedResponseWriter, ConnectionReuseTest,
TestDeleteCollectionOnDownNodes, TestLMDirichletSimilarityFactory,
TestConfigReload, SearchHandlerTest, TestSerializedLuceneMatchVersion,
HdfsRestartWhileUpdatingTest, TestDFRSimilarityFactory,
TestLMJelinekMercerSimilarityFactory, SpatialHeatmapFacetsTest,
DistanceFunctionTest, TestManagedResourceStorage, TestDownShardTolerantSearch,
TestSlowCompositeReaderWrapper, DistributedIntervalFacetingTest,
MBeansHandlerTest, TestLuceneMatchVersion, TestSchemaNameResource,
SuggesterWFSTTest, TestSolrCloudWithDelegationTokens]
[junit4] Completed [192/662 (1!)] on J1 in 1.59s, 8 tests, 1 failure <<<
FAILURES!
[...truncated 1124 lines...]
[junit4] Suite: org.apache.solr.cloud.hdfs.HdfsRecoveryZkTest
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_AF8E0FECB28DF132-001/init-core-data-001
[junit4] 2> 2447503 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2447504 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_AF8E0FECB28DF132-001/tempDir-001
[junit4] 2> 2447505 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2447505 INFO (Thread-5040) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2447505 INFO (Thread-5040) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2447606 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:34586
[junit4] 2> 2447617 INFO (jetty-launcher-9412-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2447618 INFO (jetty-launcher-9412-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2447621 INFO (jetty-launcher-9412-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@6ef0371f{/solr,null,AVAILABLE}
[junit4] 2> 2447621 INFO (jetty-launcher-9412-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@16c89073{/solr,null,AVAILABLE}
[junit4] 2> 2447621 INFO (jetty-launcher-9412-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@39c2e9dd{HTTP/1.1,[http/1.1]}{127.0.0.1:47284}
[junit4] 2> 2447621 INFO (jetty-launcher-9412-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@1f12ada8{HTTP/1.1,[http/1.1]}{127.0.0.1:58481}
[junit4] 2> 2447621 INFO (jetty-launcher-9412-thread-2) [ ]
o.e.j.s.Server Started @2451502ms
[junit4] 2> 2447621 INFO (jetty-launcher-9412-thread-1) [ ]
o.e.j.s.Server Started @2451502ms
[junit4] 2> 2447621 INFO (jetty-launcher-9412-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=47284}
[junit4] 2> 2447621 INFO (jetty-launcher-9412-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=58481}
[junit4] 2> 2447622 ERROR (jetty-launcher-9412-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2447622 INFO (jetty-launcher-9412-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.4.0
[junit4] 2> 2447623 INFO (jetty-launcher-9412-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2447623 INFO (jetty-launcher-9412-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2447623 INFO (jetty-launcher-9412-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-12-15T08:09:32.991Z
[junit4] 2> 2447622 ERROR (jetty-launcher-9412-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2447623 INFO (jetty-launcher-9412-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.4.0
[junit4] 2> 2447623 INFO (jetty-launcher-9412-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2447624 INFO (jetty-launcher-9412-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2447624 INFO (jetty-launcher-9412-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-12-15T08:09:32.992Z
[junit4] 2> 2447627 INFO (jetty-launcher-9412-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2447628 INFO (jetty-launcher-9412-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2447634 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x159018779ca0002, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2447634 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x159018779ca0001, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2447635 INFO (jetty-launcher-9412-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2447637 INFO (jetty-launcher-9412-thread-2) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2447637 INFO (jetty-launcher-9412-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34586/solr
[junit4] 2> 2447638 INFO (jetty-launcher-9412-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34586/solr
[junit4] 2> 2447677 INFO (jetty-launcher-9412-thread-2)
[n:127.0.0.1:47284_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:47284_solr
[junit4] 2> 2447678 INFO (jetty-launcher-9412-thread-1)
[n:127.0.0.1:58481_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:58481_solr
[junit4] 2> 2447680 INFO (jetty-launcher-9412-thread-2)
[n:127.0.0.1:47284_solr ] o.a.s.c.Overseer Overseer
(id=97110548340670470-127.0.0.1:47284_solr-n_0000000000) starting
[junit4] 2> 2447680 INFO
(zkCallback-9423-thread-1-processing-n:127.0.0.1:47284_solr)
[n:127.0.0.1:47284_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2447680 INFO
(zkCallback-9422-thread-1-processing-n:127.0.0.1:58481_solr)
[n:127.0.0.1:58481_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 2447694 INFO (jetty-launcher-9412-thread-2)
[n:127.0.0.1:47284_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:47284_solr
[junit4] 2> 2447698 INFO
(zkCallback-9423-thread-1-processing-n:127.0.0.1:47284_solr)
[n:127.0.0.1:47284_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2447698 INFO
(zkCallback-9422-thread-1-processing-n:127.0.0.1:58481_solr)
[n:127.0.0.1:58481_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 2447757 INFO (jetty-launcher-9412-thread-1)
[n:127.0.0.1:58481_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_AF8E0FECB28DF132-001/tempDir-001/node1/.
[junit4] 2> 2447824 INFO (jetty-launcher-9412-thread-2)
[n:127.0.0.1:47284_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_AF8E0FECB28DF132-001/tempDir-001/node2/.
[junit4] 2> 2447832 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2447834 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34586/solr ready
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 2447889 WARN
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 2447898 WARN
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 2447901 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 2447918 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ] o.m.log
Extract
jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/hdfs
to ./temp/Jetty_solaris.vm_44299_hdfs____.n1s6cc/webapp
[junit4] 2> 2448424 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ] o.m.log
Started HttpServer2$SelectChannelConnectorWithSafeStartup@solaris-vm:44299
[junit4] 2> 2448528 WARN
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 2448531 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 2448545 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ] o.m.log
Extract
jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_60038_datanode____.5zzkzi/webapp
[junit4] 2> 2449091 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ] o.m.log
Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:60038
[junit4] 2> 2449158 WARN
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 2449159 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ] o.m.log
jetty-6.1.26
[junit4] 2> 2449195 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ] o.m.log
Extract
jar:file:/export/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_44324_datanode____i14qzg/webapp
[junit4] 2> 2449252 INFO (IPC Server handler 2 on 33650) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-e4280b5b-7b03-4134-a610-1ac3909cacb2 node
DatanodeRegistration(127.0.0.1:55916,
datanodeUuid=82e6b4f1-e8ac-4c8f-b7f2-4456f16f71f4, infoPort=53218,
infoSecurePort=0, ipcPort=53220,
storageInfo=lv=-56;cid=testClusterID;nsid=1144978801;c=0), blocks: 0,
hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 2449252 INFO (IPC Server handler 2 on 33650) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-925d2046-6586-4bc0-9abc-86b129eac940 node
DatanodeRegistration(127.0.0.1:55916,
datanodeUuid=82e6b4f1-e8ac-4c8f-b7f2-4456f16f71f4, infoPort=53218,
infoSecurePort=0, ipcPort=53220,
storageInfo=lv=-56;cid=testClusterID;nsid=1144978801;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 2449717 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ] o.m.log
Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:44324
[junit4] 2> 2449813 INFO (IPC Server handler 5 on 33650) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-8c0e8556-78d0-409e-87aa-71cf4e674f6b node
DatanodeRegistration(127.0.0.1:48936,
datanodeUuid=318d11e6-63ff-4c89-ab23-4c254071866c, infoPort=54387,
infoSecurePort=0, ipcPort=65472,
storageInfo=lv=-56;cid=testClusterID;nsid=1144978801;c=0), blocks: 0,
hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 2449813 INFO (IPC Server handler 5 on 33650) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-8e68e8e4-d0c1-4e29-babb-594f059efded node
DatanodeRegistration(127.0.0.1:48936,
datanodeUuid=318d11e6-63ff-4c89-ab23-4c254071866c, infoPort=54387,
infoSecurePort=0, ipcPort=65472,
storageInfo=lv=-56;cid=testClusterID;nsid=1144978801;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 2449917 INFO
(TEST-HdfsRecoveryZkTest.test-seed#[AF8E0FECB28DF132]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 2449919 INFO (qtp1031681066-42365) [n:127.0.0.1:58481_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&maxShardsPerNode=1&name=recoverytest&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2449923 INFO
(OverseerThreadFactory-10597-thread-1-processing-n:127.0.0.1:47284_solr)
[n:127.0.0.1:47284_solr ] o.a.s.c.CreateCollectionCmd Create collection
recoverytest
[junit4] 2> 2450032 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=recoverytest_shard1_replica2&action=CREATE&numShards=1&collection=recoverytest&shard=shard1&wt=javabin&version=2
[junit4] 2> 2450032 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=recoverytest_shard1_replica1&action=CREATE&numShards=1&collection=recoverytest&shard=shard1&wt=javabin&version=2
[junit4] 2> 2450141 INFO
(zkCallback-9423-thread-1-processing-n:127.0.0.1:47284_solr)
[n:127.0.0.1:47284_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/recoverytest/state.json] for collection [recoverytest] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2450141 INFO
(zkCallback-9422-thread-1-processing-n:127.0.0.1:58481_solr)
[n:127.0.0.1:58481_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/recoverytest/state.json] for collection [recoverytest] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2451046 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.4.0
[junit4] 2> 2451046 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.4.0
[junit4] 2> 2451073 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.s.IndexSchema
[recoverytest_shard1_replica1] Schema name=minimal
[junit4] 2> 2451073 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.s.IndexSchema
[recoverytest_shard1_replica2] Schema name=minimal
[junit4] 2> 2451076 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2451076 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2451078 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.CoreContainer
Creating SolrCore 'recoverytest_shard1_replica2' using configuration from
collection recoverytest
[junit4] 2> 2451078 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.CoreContainer
Creating SolrCore 'recoverytest_shard1_replica1' using configuration from
collection recoverytest
[junit4] 2> 2451085 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://solaris-vm:33650/data
[junit4] 2> 2451085 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 2451086 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.SolrCore
[[recoverytest_shard1_replica2] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_AF8E0FECB28DF132-001/tempDir-001/node1/recoverytest_shard1_replica2],
dataDir=[hdfs://solaris-vm:33650/data/recoverytest/core_node1/data/]
[junit4] 2> 2451086 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://solaris-vm:33650/data
[junit4] 2> 2451086 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://solaris-vm:33650/data/recoverytest/core_node1/data/snapshot_metadata
[junit4] 2> 2451086 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 2451086 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.SolrCore
[[recoverytest_shard1_replica1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-6.x-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_AF8E0FECB28DF132-001/tempDir-001/node2/recoverytest_shard1_replica1],
dataDir=[hdfs://solaris-vm:33650/data/recoverytest/core_node2/data/]
[junit4] 2> 2451086 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://solaris-vm:33650/data/recoverytest/core_node2/data/snapshot_metadata
[junit4] 2> 2451095 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 2451095 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[16777216] will allocate [1] slabs and use ~[16777216] bytes
[junit4] 2> 2451095 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 2451096 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[16777216] will allocate [1] slabs and use ~[16777216] bytes
[junit4] 2> 2451095 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 2451096 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 2451160 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 2451161 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://solaris-vm:33650/data/recoverytest/core_node2/data
[junit4] 2> 2451177 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 2451179 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://solaris-vm:33650/data/recoverytest/core_node1/data
[junit4] 2> 2451190 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://solaris-vm:33650/data/recoverytest/core_node2/data/index
[junit4] 2> 2451198 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 2451198 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[16777216] will allocate [1] slabs and use ~[16777216] bytes
[junit4] 2> 2451198 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 2451199 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://solaris-vm:33650/data/recoverytest/core_node1/data/index
[junit4] 2> 2451206 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 2451206 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[16777216] will allocate [1] slabs and use ~[16777216] bytes
[junit4] 2> 2451206 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache
[junit4] 2> 2451281 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 2451284 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 2451602 INFO (IPC Server handler 5 on 33650) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:48936 is
added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-925d2046-6586-4bc0-9abc-86b129eac940:NORMAL:127.0.0.1:55916|RBW],
ReplicaUC[[DISK]DS-8e68e8e4-d0c1-4e29-babb-594f059efded:NORMAL:127.0.0.1:48936|FINALIZED]]}
size 0
[junit4] 2> 2451603 INFO (IPC Server handler 4 on 33650) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:55916 is
added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-8e68e8e4-d0c1-4e29-babb-594f059efded:NORMAL:127.0.0.1:48936|FINALIZED],
ReplicaUC[[DISK]DS-e4280b5b-7b03-4134-a610-1ac3909cacb2:NORMAL:127.0.0.1:55916|FINALIZED]]}
size 0
[junit4] 2> 2451604 INFO (IPC Server handler 8 on 33650) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:48936 is
added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-925d2046-6586-4bc0-9abc-86b129eac940:NORMAL:127.0.0.1:55916|RBW],
ReplicaUC[[DISK]DS-8c0e8556-78d0-409e-87aa-71cf4e674f6b:NORMAL:127.0.0.1:48936|RBW]]}
size 0
[junit4] 2> 2451604 INFO (IPC Server handler 6 on 33650) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:55916 is
added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-925d2046-6586-4bc0-9abc-86b129eac940:NORMAL:127.0.0.1:55916|RBW],
ReplicaUC[[DISK]DS-8c0e8556-78d0-409e-87aa-71cf4e674f6b:NORMAL:127.0.0.1:48936|RBW]]}
size 0
[junit4] 2> 2451620 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 2451620 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2451620 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=3
[junit4] 2> 2451630 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 2451630 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2451630 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=3
[junit4] 2> 2451632 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 2451632 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 2451646 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 2451646 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 2451656 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@7303482e[recoverytest_shard1_replica2] main]
[junit4] 2> 2451658 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2451659 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2451659 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2451660 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1553768777806512128
[junit4] 2> 2451660 INFO
(searcherExecutor-10602-thread-1-processing-n:127.0.0.1:58481_solr
x:recoverytest_shard1_replica2 s:shard1 c:recoverytest) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.SolrCore
[recoverytest_shard1_replica2] Registered new searcher
Searcher@7303482e[recoverytest_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2451667 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@282f2b03[recoverytest_shard1_replica1] main]
[junit4] 2> 2451669 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2451670 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2451670 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2451670 INFO
(searcherExecutor-10603-thread-1-processing-n:127.0.0.1:47284_solr
x:recoverytest_shard1_replica1 s:shard1 c:recoverytest) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.SolrCore
[recoverytest_shard1_replica1] Registered new searcher
Searcher@282f2b03[recoverytest_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2451670 INFO (qtp103462449-42357) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1553768777816997888
[junit4] 2> 2451671 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 2451673 INFO
(zkCallback-9423-thread-2-processing-n:127.0.0.1:47284_solr)
[n:127.0.0.1:47284_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/recoverytest/state.json] for collection [recoverytest] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2451674 INFO
(zkCallback-9422-thread-1-processing-n:127.0.0.1:58481_solr)
[n:127.0.0.1:58481_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/recoverytest/state.json] for collection [recoverytest] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 2452180 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2452180 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2452180 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:58481/solr/recoverytest_shard1_replica2/
[junit4] 2> 2452181 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.PeerSync
PeerSync: core=recoverytest_shard1_replica2 url=http://127.0.0.1:58481/solr
START replicas=[http://127.0.0.1:47284/solr/recoverytest_shard1_replica1/]
nUpdates=100
[junit4] 2> 2452181 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.PeerSync
PeerSync: core=recoverytest_shard1_replica2 url=http://127.0.0.1:58481/solr
DONE. We have no versions. sync failed.
[junit4] 2> 2452184 INFO (qtp103462449-42360) [n:127.0.0.1:47284_solr
c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1]
o.a.s.c.S.Request [recoverytest_shard1_replica1] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 2452184 INFO (qtp1031681066-42367) [n:127.0.0.1:58481_solr
c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.SyncStrategy
Leader's attempt to sync with shard failed, moving to the next candidate
[juni
[...truncated too long message...]
getTotalBlocks(BlockManager.java:3370)
[junit4] 2> at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:5729)
[junit4] 2> ... 54 more
[junit4] 2> 2465292 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:34586 34586
[junit4] 2> 2465293 INFO
(SUITE-HdfsRecoveryZkTest-seed#[AF8E0FECB28DF132]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62):
{rnd_b=PostingsFormat(name=LuceneFixedGap),
_version_=PostingsFormat(name=Memory doPackFST= false),
a_t=PostingsFormat(name=Memory doPackFST= false),
a_i=PostingsFormat(name=LuceneFixedGap),
id=PostingsFormat(name=LuceneFixedGap)}, docValues:{}, maxPointsInLeafNode=310,
maxMBSortInHeap=6.384885969277208,
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=ar-EG,
timezone=Africa/Bujumbura
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_112
(64-bit)/cpus=3,threads=2,free=115312024,total=492830720
[junit4] 2> NOTE: All tests run in this JVM: [TestClusterStateMutator,
TestFieldCacheSort, TestPKIAuthenticationPlugin, TestRemoteStreaming,
SimplePostToolTest, TestReRankQParserPlugin, TestExactSharedStatsCache,
SpatialFilterTest, TestImplicitCoreProperties, TestCloudDeleteByQuery,
TestUniqueKeyFieldResource, PreAnalyzedUpdateProcessorTest,
TestFileDictionaryLookup, ClusterStateUpdateTest, TestPhraseSuggestions,
SimpleFacetsTest, AnalyticsQueryTest, TestLazyCores, TestPivotHelperCode,
TestSolrCLIRunExample, CursorPagingTest, DistributedSpellCheckComponentTest,
TestSolr4Spatial2, TestManagedSchemaAPI, TestCSVResponseWriter,
OverseerRolesTest, HdfsTlogReplayBufferedWhileIndexingTest,
DistributedFacetPivotLargeTest, TestUseDocValuesAsStored2, TestFastLRUCache,
TestConfigSets, MultiTermTest, TestLegacyFieldCache, TestStressRecovery,
SecurityConfHandlerTest, TestLRUCache, TestTolerantUpdateProcessorCloud,
TestAnalyzedSuggestions, TestDefaultStatsCache, TestSolrDeletionPolicy1,
TestSolrXml, TestFunctionQuery, TestGraphTermsQParserPlugin, QueryEqualityTest,
TestSolrQueryParserDefaultOperatorResource, DistributedFacetPivotWhiteBoxTest,
FileUtilsTest, TestStandardQParsers, JavabinLoaderTest,
CloudExitableDirectoryReaderTest, TestSchemaResource,
TestPostingsSolrHighlighter, ShufflingReplicaListTransformerTest,
DateRangeFieldTest, TestFieldCacheVsDocValues,
DistributedQueryElevationComponentTest, TestConfigSetImmutable,
CloneFieldUpdateProcessorFactoryTest, ShardRoutingTest,
TestCollationFieldDocValues, TestStressCloudBlindAtomicUpdates,
TestCollectionAPI, TestSizeLimitedDistributedMap, TestExportWriter,
TestFieldTypeResource, DocumentBuilderTest, TestHighlightDedupGrouping,
ShardSplitTest, ReplaceNodeTest, TestHashPartitioner, HLLSerializationTest,
SynonymTokenizerTest, TestConfigSetsAPIExclusivity,
UUIDUpdateProcessorFallbackTest, CachingDirectoryFactoryTest,
AnalysisAfterCoreReloadTest, TestSweetSpotSimilarityFactory,
DirectSolrConnectionTest, OverseerTaskQueueTest, TestNumericTerms64,
DisMaxRequestHandlerTest, CdcrUpdateLogTest, TestRestManager,
LeaderElectionTest, TestCustomStream, TestCloudPivotFacet, TestCryptoKeys,
MergeStrategyTest, UtilsToolTest, TestSimpleQParserPlugin, TestMissingGroups,
TestTrieFacet, ResponseHeaderTest, JSONWriterTest, HdfsSyncSliceTest, TestSort,
TestBackupRepositoryFactory, StandardRequestHandlerTest,
TestPartialUpdateDeduplication, TestElisionMultitermQuery, BJQParserTest,
RecoveryAfterSoftCommitTest, TestDocumentBuilder,
LeaderFailoverAfterPartitionTest, TestNamedUpdateProcessors, TestSQLHandler,
AssignTest, SolrPluginUtilsTest, TestDynamicFieldResource, TestReloadDeadlock,
SyncSliceTest, SolrRequestParserTest, BlockDirectoryTest,
WrapperMergePolicyFactoryTest, TestCursorMarkWithoutUniqueKey,
CustomCollectionTest, TimeZoneUtilsTest,
PreAnalyzedFieldManagedSchemaCloudTest, TestLeaderInitiatedRecoveryThread,
HardAutoCommitTest, HdfsDirectoryTest, TestCSVLoader,
TestManagedSynonymFilterFactory, XsltUpdateRequestHandlerTest,
StatsReloadRaceTest, BlockJoinFacetRandomTest, TestRandomDVFaceting,
HdfsWriteToMultipleCollectionsTest, PluginInfoTest, TestLRUStatsCache,
SolrCLIZkUtilsTest, DeleteStatusTest, CoreAdminCreateDiscoverTest,
SystemInfoHandlerTest, TestGraphMLResponseWriter, TestExtendedDismaxParser,
TestInitParams, PeerSyncWithIndexFingerprintCachingTest, RecoveryZkTest,
TestReqParamsAPI, HdfsDirectoryFactoryTest,
ClassificationUpdateProcessorIntegrationTest, TestBinaryResponseWriter,
HdfsChaosMonkeySafeLeaderTest, TestBinaryField, TestHdfsBackupRestoreCore,
TestSha256AuthenticationProvider, StatelessScriptUpdateProcessorFactoryTest,
SolrCoreTest, TestSolrCloudWithKerberosAlt, TestTolerantSearch,
ZkStateWriterTest, UniqFieldsUpdateProcessorFactoryTest, DOMUtilTest,
IndexSchemaRuntimeFieldTest, TestPerFieldSimilarity, OverseerTest,
TestSolrIndexConfig, ZkNodePropsTest, SchemaVersionSpecificBehaviorTest,
FullSolrCloudDistribCmdsTest, TestRandomFaceting, ZkSolrClientTest,
AutoCommitTest, TestDistributedGrouping, TestFaceting,
TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet,
TestStressReorder, TestJoin, TestReload, TestStressVersions,
DistributedTermsComponentTest, TestCoreContainer, SpellCheckComponentTest,
TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest,
PeerSyncTest, BadIndexSchemaTest, ConvertedLegacyTest, BasicFunctionalityTest,
DirectUpdateHandlerTest, TestIndexSearcher, HighlighterTest,
SoftAutoCommitTest, ShowFileRequestHandlerTest, CurrencyFieldXmlFileTest,
SolrIndexSplitterTest, SuggesterFSTTest, CoreAdminHandlerTest, DocValuesTest,
SuggesterTSTTest, TestStressLucene, PolyFieldTest,
WordBreakSolrSpellCheckerTest, SolrCoreCheckLockOnStartupTest, TestUpdate,
TestWordDelimiterFilterFactory, DocValuesMultiTest, SolrInfoMBeanTest,
TestSurroundQueryParser, TestQueryUtils, DirectSolrSpellCheckerTest,
DocumentAnalysisRequestHandlerTest, TestOmitPositions,
PathHierarchyTokenizerFactoryTest, RequiredFieldsTest,
FastVectorHighlighterTest, TestJmxIntegration, JsonLoaderTest,
TestComponentsName, TestBinaryResponseWriter, TestLFUCache,
PreAnalyzedFieldTest, TestSystemIdResolver, PrimUtilsTest, DateFieldTest,
SpellingQueryConverterTest, RAMDirectoryFactoryTest, TestSolrJ, TestUtils,
UUIDFieldTest, CircularListTest, DistributedMLTComponentTest, TestRTGBase,
SolrTestCaseJ4Test, TestDistributedMissingSort, TestJettySolrRunner,
ActionThrottleTest, AsyncCallRequestStatusResponseTest,
BaseCdcrDistributedZkTest, CdcrBootstrapTest, CollectionTooManyReplicasTest,
CollectionsAPISolrJTest, DeleteShardTest, DistributedQueueTest,
DistributedVersionInfoTest, LeaderElectionContextKeyTest,
LeaderInitiatedRecoveryOnCommitTest, LeaderInitiatedRecoveryOnShardRestartTest,
MigrateRouteKeyTest, MultiThreadedOCPTest, OverseerModifyCollectionTest,
RestartWhileUpdatingTest, RollingRestartTest, SSLMigrationTest,
SaslZkACLProviderTest, SharedFSAutoReplicaFailoverUtilsTest,
SimpleCollectionCreateDeleteTest, TestCloudRecovery,
TestExclusionRuleCollectionAccess, TestLeaderElectionZkExpiry,
TestLocalFSCloudBackupRestore, TestMiniSolrCloudClusterSSL,
TestOnReconnectListenerSupport, TestRebalanceLeaders, TestSegmentSorting,
TestShortCircuitedRequests, TestStressLiveNodes, HdfsBasicDistributedZk2Test,
HdfsBasicDistributedZkTest, HdfsChaosMonkeyNothingIsSafeTest,
HdfsNNFailoverTest, HdfsRecoveryZkTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HdfsRecoveryZkTest
-Dtests.seed=AF8E0FECB28DF132 -Dtests.slow=true -Dtests.locale=ar-EG
-Dtests.timezone=Africa/Bujumbura -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | HdfsRecoveryZkTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 1
object(s) that were not released!!! [HdfsTransactionLog]
[junit4] >
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] > at
org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
[junit4] > at
org.apache.solr.update.HdfsTransactionLog.<init>(HdfsTransactionLog.java:130)
[junit4] > at
org.apache.solr.update.HdfsUpdateLog.init(HdfsUpdateLog.java:202)
[junit4] > at
org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:137)
[junit4] > at
org.apache.solr.update.UpdateHandler.<init>(UpdateHandler.java:94)
[junit4] > at
org.apache.solr.update.DirectUpdateHandler2.<init>(DirectUpdateHandler2.java:102)
[junit4] > at
java.lang.reflect.Constructor.newInstance(Constructor.java:423)
[junit4] > at
org.apache.solr.core.SolrCore.createInstance(SolrCore.java:704)
[junit4] > at
org.apache.solr.core.SolrCore.createUpdateHandler(SolrCore.java:766)
[junit4] > at
org.apache.solr.core.SolrCore.initUpdateHandler(SolrCore.java:1005)
[junit4] > at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:870)
[junit4] > at
org.apache.solr.core.SolrCore.<init>(SolrCore.java:774)
[junit4] > at
org.apache.solr.core.CoreContainer.create(CoreContainer.java:842)
[junit4] > at
org.apache.solr.core.CoreContainer.lambda$load$0(CoreContainer.java:498)
[junit4] > at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at
__randomizedtesting.SeedInfo.seed([AF8E0FECB28DF132]:0)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:266)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [531/662 (2!)] on J0 in 48.94s, 1 test, 1 failure <<<
FAILURES!
[...truncated 62823 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
