Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Solaris/1057/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
3 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation
Error Message:
2 threads leaked from SUITE scope at
org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation: 1)
Thread[id=3590, name=jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358),
state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at java.lang.Thread.sleep(Native Method) at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
2) Thread[id=3591, name=jetty-launcher-581-thread-1-EventThread, state=WAITING,
group=TGRP-TestSolrCloudWithSecureImpersonation] at
sun.misc.Unsafe.park(Native Method) at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:
1) Thread[id=3590,
name=jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358),
state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at java.lang.Thread.sleep(Native Method)
at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
2) Thread[id=3591, name=jetty-launcher-581-thread-1-EventThread,
state=WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)
at __randomizedtesting.SeedInfo.seed([3CA79A4EDCCE81E0]:0)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=3590, name=jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358),
state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at java.lang.Thread.sleep(Native Method) at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=3590,
name=jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358),
state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
at java.lang.Thread.sleep(Native Method)
at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
at __randomizedtesting.SeedInfo.seed([3CA79A4EDCCE81E0]:0)
FAILED: org.apache.solr.update.SolrIndexMetricsTest.testIndexMetrics
Error Message:
minorMerge: 3 expected:<4> but was:<3>
Stack Trace:
java.lang.AssertionError: minorMerge: 3 expected:<4> but was:<3>
at
__randomizedtesting.SeedInfo.seed([3CA79A4EDCCE81E0:F077A7F21C407ADB]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at
org.apache.solr.update.SolrIndexMetricsTest.testIndexMetrics(SolrIndexMetricsTest.java:70)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10673 lines...]
[junit4] Suite: org.apache.solr.update.SolrIndexMetricsTest
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.update.SolrIndexMetricsTest_3CA79A4EDCCE81E0-001/init-core-data-001
[junit4] 2> 121857 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 121858 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 121858 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib,
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 121895 WARN
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 121895 WARN
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergeFactor> is deprecated, configure
it on the relevant <mergePolicyFactory> instead.
[junit4] 2> 121895 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 121923 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 122098 WARN
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING:
Deprecated, please use 'df' on request instead.
[junit4] 2> 122102 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 122125 WARN
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first
MBeanServer.
[junit4] 2> 122128 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@612113d3
[junit4] 2> 122128 WARN
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first
MBeanServer.
[junit4] 2> 122134 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@612113d3
[junit4] 2> 122134 WARN
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first
MBeanServer.
[junit4] 2> 122134 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@612113d3
[junit4] 2> 122135 WARN
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first
MBeanServer.
[junit4] 2> 122136 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@612113d3
[junit4] 2> 122139 INFO (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader,
from paths:
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib,
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 122181 WARN (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is
deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 122182 WARN (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergeFactor> is
deprecated, configure it on the relevant <mergePolicyFactory> instead.
[junit4] 2> 122182 INFO (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 122251 INFO (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 122646 WARN (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema
is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 122688 INFO (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 122706 INFO (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1
[junit4] 2> 122706 WARN (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was
configured, using first MBeanServer.
[junit4] 2> 122706 INFO (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@612113d3
[junit4] 2> 122707 INFO (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.update.SolrIndexMetricsTest_3CA79A4EDCCE81E0-001/init-core-data-001/]
[junit4] 2> 122732 INFO (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 122733 INFO (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 122735 INFO (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@20e8d7d2[collection1] main]
[junit4] 2> 122735 WARN (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 122735 INFO (coreLoadExecutor-292-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 122737 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 122737 INFO
(searcherExecutor-293-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@20e8d7d2[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 122740 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testIndexMetrics
[junit4] 2> 124421 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 124421 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@3903aded
[junit4] 2> 124590 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4dac1127[collection1] main]
[junit4] 2> 124591 INFO (searcherExecutor-293-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4dac1127[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100)
Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100)
Uninverting(_3(7.0.0):C100) Uninverting(_5(7.0.0):C100)
Uninverting(_6(7.0.0):C100) Uninverting(_7(7.0.0):C100)
Uninverting(_9(7.0.0):C100) Uninverting(_a(7.0.0):C100)
Uninverting(_b(7.0.0):C100)))}
[junit4] 2> 124591 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 124609 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths:
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib,
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 124679 WARN
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 124679 WARN
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergeFactor> is deprecated, configure
it on the relevant <mergePolicyFactory> instead.
[junit4] 2> 124680 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 124695 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 124899 WARN
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 124904 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 124913 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [ ]
o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from
instancedir
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1
[junit4] 2> 124914 WARN
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was
configured, using first MBeanServer.
[junit4] 2> 124924 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@612113d3
[junit4] 2> 124927 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.m.SolrMetricManager Replacing existing reporter 'default'
in registry 'solr.core.collection1':
[org.apache.solr.metrics.reporters.SolrJmxReporter@e582c69: domain =
solr.core.collection1, service url = null, agent id = null]
[junit4] 2> 124930 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.update.SolrIndexMetricsTest_3CA79A4EDCCE81E0-001/init-core-data-001/]
[junit4] 2> 124944 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 124944 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 124946 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@18fc9600[collection1] main]
[junit4] 2> 124946 WARN
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 124946 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 124946 INFO
(searcherExecutor-297-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@18fc9600[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100)
Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100)
Uninverting(_3(7.0.0):C100) Uninverting(_5(7.0.0):C100)
Uninverting(_6(7.0.0):C100) Uninverting(_7(7.0.0):C100)
Uninverting(_9(7.0.0):C100) Uninverting(_a(7.0.0):C100)
Uninverting(_b(7.0.0):C100)))}
[junit4] 2> 125211 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 125277 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e2d260[collection1]
main]
[junit4] 2> 125277 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@223cac86
[junit4] 2> 125277 INFO
(searcherExecutor-297-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@1e2d260[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C300)
Uninverting(_8(7.0.0):C300) Uninverting(_c(7.0.0):C300)
Uninverting(_b(7.0.0):C100)))}
[junit4] 2> 125281 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1
[junit4] 2> 125286 INFO
(TEST-SolrIndexMetricsTest.testIndexMetrics-seed#[3CA79A4EDCCE81E0]) [
x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testIndexMetrics
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=SolrIndexMetricsTest -Dtests.method=testIndexMetrics
-Dtests.seed=3CA79A4EDCCE81E0 -Dtests.slow=true -Dtests.locale=ru
-Dtests.timezone=Africa/Johannesburg -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 2.55s J1 | SolrIndexMetricsTest.testIndexMetrics <<<
[junit4] > Throwable #1: java.lang.AssertionError: minorMerge: 3
expected:<4> but was:<3>
[junit4] > at
__randomizedtesting.SeedInfo.seed([3CA79A4EDCCE81E0:F077A7F21C407ADB]:0)
[junit4] > at
org.apache.solr.update.SolrIndexMetricsTest.testIndexMetrics(SolrIndexMetricsTest.java:70)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 125292 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ x:collection1]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 125292 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ x:collection1]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=716068798
[junit4] 2> 125294 INFO
(coreCloseExecutor-301-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@7f2743a4
[junit4] 2> 125298 INFO
(coreCloseExecutor-301-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 125299 INFO
(SUITE-SolrIndexMetricsTest-seed#[3CA79A4EDCCE81E0]-worker) [ x:collection1]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> NOTE: leaving temporary files on disk at:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.update.SolrIndexMetricsTest_3CA79A4EDCCE81E0-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{range_facet_l_dv=PostingsFormat(name=Memory),
multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))),
intDefault=Lucene50(blocksize=128), foo_s=Lucene50(blocksize=128),
id=PostingsFormat(name=Memory), range_facet_i_dv=Lucene50(blocksize=128),
range_facet_l=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128)},
docValues:{range_facet_l_dv=DocValuesFormat(name=Direct),
multiDefault=DocValuesFormat(name=Asserting),
intDefault=DocValuesFormat(name=Lucene70),
range_facet_i_dv=DocValuesFormat(name=Lucene70),
id=DocValuesFormat(name=Direct), foo_s=DocValuesFormat(name=Lucene70),
timestamp=DocValuesFormat(name=Lucene70),
range_facet_l=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=404,
maxMBSortInHeap=5.33850131024816, sim=RandomSimilarity(queryNorm=false): {},
locale=ru, timezone=Africa/Johannesburg
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_112
(64-bit)/cpus=3,threads=1,free=19192160,total=127614976
[junit4] 2> NOTE: All tests run in this JVM: [ResponseLogComponentTest,
DeleteInactiveReplicaTest, TestTolerantUpdateProcessorRandomCloud,
TestReversedWildcardFilterFactory, DistributedExpandComponentTest,
TestRangeQuery, TestBinaryResponseWriter, TestExportWriter, ZkSolrClientTest,
SolrIndexMetricsTest]
[junit4] Completed [19/675 (1!)] on J1 in 3.48s, 1 test, 1 failure <<<
FAILURES!
[...truncated 327 lines...]
[junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_3CA79A4EDCCE81E0-001/init-core-data-001
[junit4] 2> 525537 INFO
(SUITE-TestSolrCloudWithSecureImpersonation-seed#[3CA79A4EDCCE81E0]-worker) [
] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 525563 INFO
(SUITE-TestSolrCloudWithSecureImpersonation-seed#[3CA79A4EDCCE81E0]-worker) [
] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_3CA79A4EDCCE81E0-001/tempDir-001
[junit4] 2> 525563 INFO
(SUITE-TestSolrCloudWithSecureImpersonation-seed#[3CA79A4EDCCE81E0]-worker) [
] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 525563 INFO (Thread-823) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 525563 INFO (Thread-823) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 525663 INFO
(SUITE-TestSolrCloudWithSecureImpersonation-seed#[3CA79A4EDCCE81E0]-worker) [
] o.a.s.c.ZkTestServer start zk server on port:47358
[junit4] 2> 525673 INFO (jetty-launcher-581-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 525673 INFO (jetty-launcher-581-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 525675 INFO (jetty-launcher-581-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@13724f18{/solr,null,AVAILABLE}
[junit4] 2> 525675 INFO (jetty-launcher-581-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@3adffda6{HTTP/1.1,[http/1.1]}{127.0.0.1:37474}
[junit4] 2> 525675 INFO (jetty-launcher-581-thread-2) [ ]
o.e.j.s.Server Started @528545ms
[junit4] 2> 525675 INFO (jetty-launcher-581-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37474}
[junit4] 2> 525676 ERROR (jetty-launcher-581-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 525676 INFO (jetty-launcher-581-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 525676 INFO (jetty-launcher-581-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 525676 INFO (jetty-launcher-581-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 525676 INFO (jetty-launcher-581-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-01-05T04:29:40.360Z
[junit4] 2> 525679 INFO (jetty-launcher-581-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1e0cc7be{/solr,null,AVAILABLE}
[junit4] 2> 525680 INFO (jetty-launcher-581-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@2f3c19ca{HTTP/1.1,[http/1.1]}{127.0.0.1:52422}
[junit4] 2> 525680 INFO (jetty-launcher-581-thread-1) [ ]
o.e.j.s.Server Started @528549ms
[junit4] 2> 525680 INFO (jetty-launcher-581-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=52422}
[junit4] 2> 525680 ERROR (jetty-launcher-581-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 525680 INFO (jetty-launcher-581-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 525680 INFO (jetty-launcher-581-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 525680 INFO (jetty-launcher-581-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 525680 INFO (jetty-launcher-581-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-01-05T04:29:40.364Z
[junit4] 2> 525685 INFO (jetty-launcher-581-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 525686 INFO (jetty-launcher-581-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 525711 INFO (jetty-launcher-581-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:47358/solr
[junit4] 2> 525711 INFO (jetty-launcher-581-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:47358/solr
[junit4] 2> 525747 INFO (jetty-launcher-581-thread-2)
[n:127.0.0.1:37474_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:37474_solr
[junit4] 2> 525748 INFO (jetty-launcher-581-thread-2)
[n:127.0.0.1:37474_solr ] o.a.s.c.Overseer Overseer
(id=97228592267198468-127.0.0.1:37474_solr-n_0000000000) starting
[junit4] 2> 525751 INFO (jetty-launcher-581-thread-1)
[n:127.0.0.1:52422_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:52422_solr
[junit4] 2> 525754 INFO
(zkCallback-591-thread-1-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 525755 INFO
(zkCallback-592-thread-1-processing-n:127.0.0.1:52422_solr)
[n:127.0.0.1:52422_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 525762 INFO (jetty-launcher-581-thread-2)
[n:127.0.0.1:37474_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37474_solr
[junit4] 2> 525788 INFO
(zkCallback-591-thread-1-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 525794 INFO
(zkCallback-592-thread-1-processing-n:127.0.0.1:52422_solr)
[n:127.0.0.1:52422_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 525824 INFO (jetty-launcher-581-thread-2)
[n:127.0.0.1:37474_solr ] o.a.s.c.CoreContainer Initializing authentication
plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 525833 INFO (jetty-launcher-581-thread-2)
[n:127.0.0.1:37474_solr ] o.a.s.s.KerberosPlugin Params:
{solr.kerberos.impersonator.user.noGroups.hosts=*,
solr.kerberos.impersonator.user.bogusGroup.groups=__some_bogus_group,
token.valid=30, cookie.domain=127.0.0.1,
solr.kerberos.impersonator.user.anyHostAnyUser.groups=*,
solr.kerberos.impersonator.user.bogusGroup.hosts=*,
solr.kerberos.impersonator.user.anyHostAnyUser.hosts=*, token.validity=36000,
solr.kerberos.impersonator.user.noHosts.groups=*, type=kerberos,
solr.kerberos.impersonator.user.localHostAnyGroup.groups=*,
delegation-token.token-kind=solr-dt,
solr.kerberos.impersonator.user.wrongHost.hosts=1.1.1.1.1.1, cookie.path=/,
zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm,
solr.kerberos.impersonator.user.localHostAnyGroup.hosts=solaris-vm,localhost,127.0.0.1,
signer.secret.provider.zookeeper.path=/token,
solr.kerberos.impersonator.user.anyHostUsersGroup.groups=staff,
zk-dt-secret-manager.enable=true,
solr.kerberos.impersonator.user.wrongHost.groups=*,
kerberos.name.rules=DEFAULT, signer.secret.provider=zookeeper,
solr.kerberos.impersonator.user.anyHostUsersGroup.hosts=*}
[junit4] 2> 525837 INFO (jetty-launcher-581-thread-2)
[n:127.0.0.1:37474_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 525839 INFO (jetty-launcher-581-thread-2-EventThread)
[n:127.0.0.1:37474_solr ] o.a.c.f.s.ConnectionStateManager State change:
CONNECTED
[junit4] 2> 525865 INFO (jetty-launcher-581-thread-1)
[n:127.0.0.1:52422_solr ] o.a.s.c.CoreContainer Initializing authentication
plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 525865 INFO (jetty-launcher-581-thread-1)
[n:127.0.0.1:52422_solr ] o.a.s.s.KerberosPlugin Params:
{solr.kerberos.impersonator.user.noGroups.hosts=*,
solr.kerberos.impersonator.user.bogusGroup.groups=__some_bogus_group,
token.valid=30, cookie.domain=127.0.0.1,
solr.kerberos.impersonator.user.anyHostAnyUser.groups=*,
solr.kerberos.impersonator.user.bogusGroup.hosts=*,
solr.kerberos.impersonator.user.anyHostAnyUser.hosts=*, token.validity=36000,
solr.kerberos.impersonator.user.noHosts.groups=*, type=kerberos,
solr.kerberos.impersonator.user.localHostAnyGroup.groups=*,
delegation-token.token-kind=solr-dt,
solr.kerberos.impersonator.user.wrongHost.hosts=1.1.1.1.1.1, cookie.path=/,
zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm,
solr.kerberos.impersonator.user.localHostAnyGroup.hosts=solaris-vm,localhost,127.0.0.1,
signer.secret.provider.zookeeper.path=/token,
solr.kerberos.impersonator.user.anyHostUsersGroup.groups=staff,
zk-dt-secret-manager.enable=true,
solr.kerberos.impersonator.user.wrongHost.groups=*,
kerberos.name.rules=DEFAULT, signer.secret.provider=zookeeper,
solr.kerberos.impersonator.user.anyHostUsersGroup.hosts=*}
[junit4] 2> 525866 INFO (jetty-launcher-581-thread-1)
[n:127.0.0.1:52422_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 525869 INFO (jetty-launcher-581-thread-1-EventThread)
[n:127.0.0.1:52422_solr ] o.a.c.f.s.ConnectionStateManager State change:
CONNECTED
[junit4] 2> 525870 INFO (jetty-launcher-581-thread-2)
[n:127.0.0.1:37474_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_3CA79A4EDCCE81E0-001/tempDir-001/node2/.
[junit4] 2> 525901 INFO (jetty-launcher-581-thread-1)
[n:127.0.0.1:52422_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_3CA79A4EDCCE81E0-001/tempDir-001/node1/.
[junit4] 2> 526186 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidProxyUser-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyInvalidProxyUser
[junit4] 2> 526200 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidProxyUser-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=bar&p=bar&t=test&e=1483626580880&s=dEO7wdptLjxXAEhkTbCM3S2O0EI=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:40 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:40 GMT
[junit4] 2> 526200 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidProxyUser-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyInvalidProxyUser
[junit4] 2> 526221 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyWrongHost-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyWrongHost
[junit4] 2> 526236 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testProxyWrongHost-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=wrongHost&p=wrongHost&t=test&e=1483626580906&s=GtgPD16fDNZT1hV47WSJ/U45Qjw=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:40 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:40 GMT
[junit4] 2> 526236 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyWrongHost-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyWrongHost
[junit4] 2> 526261 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateGroup-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateGroup
[junit4] 2> 526263 INFO (qtp1293387021-3466) [n:127.0.0.1:37474_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
doAs=jenkins&action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 526263 INFO (qtp1293387021-3466) [n:127.0.0.1:37474_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={doAs=jenkins&action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 526264 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateGroup-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=anyHostUsersGroup&p=anyHostUsersGroup&t=test&e=1483626580946&s=1QP1mwmQd2+JEAztIC1O+51qy2g=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:40 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:40 GMT
[junit4] 2> 526264 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateGroup-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateGroup
[junit4] 2> 526286 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateHost-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateHost
[junit4] 2> 526287 INFO (qtp1293387021-3467) [n:127.0.0.1:37474_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
doAs=bar&action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 526287 INFO (qtp1293387021-3467) [n:127.0.0.1:37474_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={doAs=bar&action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 526287 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateHost-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=localHostAnyGroup&p=localHostAnyGroup&t=test&e=1483626580970&s=MwEiYuPg1z5R9CWdSylbxpYZ9fs=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:40 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:40 GMT
[junit4] 2> 526287 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateHost-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateHost
[junit4] 2> 526309 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateAnyHostAnyUser-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyValidateAnyHostAnyUser
[junit4] 2> 526310 INFO (qtp1293387021-3468) [n:127.0.0.1:37474_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params
doAs=bar&action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 526310 INFO (qtp1293387021-3468) [n:127.0.0.1:37474_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={doAs=bar&action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 526311 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateAnyHostAnyUser-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=anyHostAnyUser&p=anyHostAnyUser&t=test&e=1483626580994&s=jDoobnajZTGXlNNxGwDXaB+u2Fc=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:40 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:40 GMT
[junit4] 2> 526311 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyValidateAnyHostAnyUser-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyValidateAnyHostAnyUser
[junit4] 2> 526331 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testForwarding
[junit4] 2> 527022 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 527023 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:47358/solr ready
[junit4] 2> 527030 INFO (qtp1293387021-3462) [n:127.0.0.1:37474_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf1&maxShardsPerNode=1&name=forwardingCollection&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 527035 INFO
(OverseerThreadFactory-1611-thread-1-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.CreateCollectionCmd Create collection
forwardingCollection
[junit4] 2> 527144 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=forwardingCollection_shard1_replica1&action=CREATE&numShards=1&collection=forwardingCollection&shard=shard1&wt=javabin&version=2
[junit4] 2> 527254 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/forwardingCollection/state.json] for collection
[forwardingCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 528169 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 528198 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.s.IndexSchema [forwardingCollection_shard1_replica1] Schema name=test
[junit4] 2> 528284 WARN (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.s.IndexSchema [forwardingCollection_shard1_replica1] default search field
in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 528291 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 528309 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.c.CoreContainer Creating SolrCore 'forwardingCollection_shard1_replica1'
using configuration from collection forwardingCollection
[junit4] 2> 528309 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.c.SolrCore [[forwardingCollection_shard1_replica1] ] Opening new SolrCore
at
[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_3CA79A4EDCCE81E0-001/tempDir-001/node2/forwardingCollection_shard1_replica1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-master-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.TestSolrCloudWithSecureImpersonation_3CA79A4EDCCE81E0-001/tempDir-001/node2/./forwardingCollection_shard1_replica1/data/]
[junit4] 2> 528309 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@612113d3
[junit4] 2> 528311 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy:
minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.23645653469846994]
[junit4] 2> 528328 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 528328 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 528329 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 528329 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 528330 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=33, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=77.69140625,
floorSegmentMB=1.76171875, forceMergeDeletesPctAllowed=21.93474311168293,
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.6396975797826988
[junit4] 2> 528330 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@3a4ecfd3[forwardingCollection_shard1_replica1] main]
[junit4] 2> 528331 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 528336 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.s.DirectSolrSpellChecker init:
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 528353 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 528353 INFO
(searcherExecutor-1616-thread-1-processing-n:127.0.0.1:37474_solr
x:forwardingCollection_shard1_replica1 s:shard1 c:forwardingCollection)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1
x:forwardingCollection_shard1_replica1] o.a.s.h.c.SpellCheckComponent Loading
spell index for spellchecker: default
[junit4] 2> 528354 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1555657479199653888
[junit4] 2> 528354 INFO
(searcherExecutor-1616-thread-1-processing-n:127.0.0.1:37474_solr
x:forwardingCollection_shard1_replica1 s:shard1 c:forwardingCollection)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1
x:forwardingCollection_shard1_replica1] o.a.s.h.c.SpellCheckComponent Loading
spell index for spellchecker: direct
[junit4] 2> 528354 INFO
(searcherExecutor-1616-thread-1-processing-n:127.0.0.1:37474_solr
x:forwardingCollection_shard1_replica1 s:shard1 c:forwardingCollection)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1
x:forwardingCollection_shard1_replica1] o.a.s.h.c.SpellCheckComponent Loading
spell index for spellchecker: wordbreak
[junit4] 2> 528354 INFO
(searcherExecutor-1616-thread-1-processing-n:127.0.0.1:37474_solr
x:forwardingCollection_shard1_replica1 s:shard1 c:forwardingCollection)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1
x:forwardingCollection_shard1_replica1] o.a.s.h.c.SpellCheckComponent Loading
spell index for spellchecker: multipleFields
[junit4] 2> 528354 INFO
(searcherExecutor-1616-thread-1-processing-n:127.0.0.1:37474_solr
x:forwardingCollection_shard1_replica1 s:shard1 c:forwardingCollection)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1
x:forwardingCollection_shard1_replica1] o.a.s.h.c.SpellCheckComponent Loading
spell index for spellchecker: jarowinkler
[junit4] 2> 528355 INFO
(searcherExecutor-1616-thread-1-processing-n:127.0.0.1:37474_solr
x:forwardingCollection_shard1_replica1 s:shard1 c:forwardingCollection)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1
x:forwardingCollection_shard1_replica1] o.a.s.h.c.SpellCheckComponent Loading
spell index for spellchecker: external
[junit4] 2> 528355 INFO
(searcherExecutor-1616-thread-1-processing-n:127.0.0.1:37474_solr
x:forwardingCollection_shard1_replica1 s:shard1 c:forwardingCollection)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1
x:forwardingCollection_shard1_replica1] o.a.s.h.c.SpellCheckComponent Loading
spell index for spellchecker: freq
[junit4] 2> 528355 INFO
(searcherExecutor-1616-thread-1-processing-n:127.0.0.1:37474_solr
x:forwardingCollection_shard1_replica1 s:shard1 c:forwardingCollection)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1
x:forwardingCollection_shard1_replica1] o.a.s.h.c.SpellCheckComponent Loading
spell index for spellchecker: fqcn
[junit4] 2> 528356 INFO
(searcherExecutor-1616-thread-1-processing-n:127.0.0.1:37474_solr
x:forwardingCollection_shard1_replica1 s:shard1 c:forwardingCollection)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1
x:forwardingCollection_shard1_replica1] o.a.s.h.c.SpellCheckComponent Loading
spell index for spellchecker: perDict
[junit4] 2> 528358 INFO
(searcherExecutor-1616-thread-1-processing-n:127.0.0.1:37474_solr
x:forwardingCollection_shard1_replica1 s:shard1 c:forwardingCollection)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1
x:forwardingCollection_shard1_replica1] o.a.s.c.SolrCore
[forwardingCollection_shard1_replica1] Registered new searcher
Searcher@3a4ecfd3[forwardingCollection_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 528364 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 528364 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 528364 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:37474/solr/forwardingCollection_shard1_replica1/
[junit4] 2> 528364 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 528364 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.c.SyncStrategy
http://127.0.0.1:37474/solr/forwardingCollection_shard1_replica1/ has no
replicas
[junit4] 2> 528373 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:37474/solr/forwardingCollection_shard1_replica1/ shard1
[junit4] 2> 528478 INFO
(zkCallback-591-thread-3-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/forwardingCollection/state.json] for collection
[forwardingCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 528526 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 528530 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 x:forwardingCollection_shard1_replica1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=forwardingCollection_shard1_replica1&action=CREATE&numShards=1&collection=forwardingCollection&shard=shard1&wt=javabin&version=2}
status=0 QTime=1387
[junit4] 2> 528531 WARN
(httpShardExecutor-1607-thread-1-processing-n:127.0.0.1:37474_solr
[http:////127.0.0.1:37474//solr] http:////127.0.0.1:37474//solr)
[n:127.0.0.1:37474_solr ] o.a.h.c.p.ResponseProcessCookies Invalid cookie
header: "Set-Cookie:
hadoop.auth="u=$&p=$&t=test&e=1483626581827&s=4eBT+M/ApRy9Xxl3eiGeQjBHrTw=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:41 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:41 GMT
[junit4] 2> 528536 INFO (qtp1293387021-3462) [n:127.0.0.1:37474_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 528632 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/forwardingCollection/state.json] for collection
[forwardingCollection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 529038 INFO
(OverseerCollectionConfigSetProcessor-97228592267198468-127.0.0.1:37474_solr-n_0000000000)
[n:127.0.0.1:37474_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 529536 INFO (qtp1293387021-3462) [n:127.0.0.1:37474_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf1&maxShardsPerNode=1&name=forwardingCollection&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=2507
[junit4] 2> 529537 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=user&p=user&t=test&e=1483626581714&s=vwPg7wf/gLkHVYW0ZLpU4L4XqWY=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:41 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:41 GMT
[junit4] 2> 529537 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish -
collection: forwardingCollection failOnTimeout:true timeout (sec):100
[junit4] 2> 529538 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection:
forwardingCollection
[junit4] 2> 529541 INFO (qtp1293387021-3467) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 r:core_node1
x:forwardingCollection_shard1_replica1] o.a.s.c.S.Request
[forwardingCollection_shard1_replica1] webapp=/solr path=/select
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 529541 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=user&p=user&t=test&e=1483626584224&s=Uf8LCETDAhOZuNMINMwGWOSFZ88=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:44 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:44 GMT
[junit4] 2> 529548 INFO (qtp1293387021-3465) [n:127.0.0.1:37474_solr
c:forwardingCollection s:shard1 r:core_node1
x:forwardingCollection_shard1_replica1] o.a.s.c.S.Request
[forwardingCollection_shard1_replica1] webapp=/solr path=/select
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 529549 WARN (qtp918162647-3472) [n:127.0.0.1:52422_solr ]
o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=$&p=$&t=test&e=1483626584232&s=zq33IfnwGBU03UWZNmNyGpVLkfE=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:44 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:44 GMT
[junit4] 2> 529550 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=user&p=user&t=test&e=1483626584230&s=n7EHu6wnyiWJxkQe/ByNM1dvjEw=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:44 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:44 GMT
[junit4] 2> 529550 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=$&p=$&t=test&e=1483626584232&s=zq33IfnwGBU03UWZNmNyGpVLkfE=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:44 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:44 GMT
[junit4] 2> 529550 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testForwarding-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testForwarding
[junit4] 2> 529575 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigHosts-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNoConfigHosts
[junit4] 2> 529579 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigHosts-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=noHosts&p=noHosts&t=test&e=1483626584260&s=wRxFP6wFxkO9YpNaYjNXMmynL7M=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:44 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:44 GMT
[junit4] 2> 529579 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigHosts-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNoConfigHosts
[junit4] 2> 529607 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyUnknownRemote-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyUnknownRemote
[junit4] 2> 549773 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Exception causing close of session 0x1596ce3b8190006 due
to java.io.IOException: Connection reset by peer
[junit4] 2> 549775 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testProxyUnknownRemote-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=localHostAnyGroup&p=localHostAnyGroup&t=test&e=1483626584291&s=8ag6yBfLKMH+60cab8j2zdLwG7w=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:29:44 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:29:44 GMT
[junit4] 2> 549775 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyUnknownRemote-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyUnknownRemote
[junit4] 2> 549801 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigGroups-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNoConfigGroups
[junit4] 2> 549803 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigGroups-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=noGroups&p=noGroups&t=test&e=1483626604485&s=GbqgbmR0HlZ3RduMjPJb6kSuWT0=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:30:04 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:30:04 GMT
[junit4] 2> 549803 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyNoConfigGroups-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNoConfigGroups
[junit4] 2> 549835 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidRemote-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyInvalidRemote
[junit4] 2> 549847 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidRemote-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=localHostAnyGroup&p=localHostAnyGroup&t=test&e=1483626604526&s=mhs1X6tL4Qjbr6zc1IS9AE3RJoQ=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:30:04 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:30:04 GMT
[junit4] 2> 549848 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidRemote-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyInvalidRemote
[junit4] 2> 549873 WARN (zkCallback-599-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5169a94 name:
ZooKeeperConnection Watcher:127.0.0.1:47358/solr got event WatchedEvent
state:Disconnected type:None path:null path: null type: None
[junit4] 2> 549873 WARN (zkCallback-599-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has disconnected
[junit4] 2> 549873 INFO (jetty-launcher-581-thread-2-EventThread)
[n:127.0.0.1:37474_solr ] o.a.c.f.s.ConnectionStateManager State change:
SUSPENDED
[junit4] 2> 549874 INFO (jetty-launcher-581-thread-1-EventThread)
[n:127.0.0.1:52422_solr ] o.a.c.f.s.ConnectionStateManager State change:
SUSPENDED
[junit4] 2> 549876 WARN
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@6d3fd2e9 name:
ZooKeeperConnection Watcher:127.0.0.1:47358/solr got event WatchedEvent
state:Disconnected type:None path:null path: null type: None
[junit4] 2> 549876 WARN
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ConnectionManager zkClient has
disconnected
[junit4] 2> 549880 WARN
(zkCallback-592-thread-1-processing-n:127.0.0.1:52422_solr)
[n:127.0.0.1:52422_solr ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@1b45e9d4 name:
ZooKeeperConnection Watcher:127.0.0.1:47358/solr got event WatchedEvent
state:Disconnected type:None path:null path: null type: None
[junit4] 2> 549880 WARN
(zkCallback-592-thread-1-processing-n:127.0.0.1:52422_solr)
[n:127.0.0.1:52422_solr ] o.a.s.c.c.ConnectionManager zkClient has
disconnected
[junit4] 2> 549882 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyNullProxyUser-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyNullProxyUser
[junit4] 2> 549882 INFO (qtp1293387021-3464) [ ]
o.a.s.s.SolrDispatchFilter Error authenticating
[junit4] 2> java.lang.IllegalArgumentException: userName is NULL, empty or
contains a '&'
[junit4] 2> at
org.apache.hadoop.security.authentication.util.AuthToken.checkForIllegalArgument(AuthToken.java:87)
[junit4] 2> at
org.apache.hadoop.security.authentication.util.AuthToken.<init>(AuthToken.java:70)
[junit4] 2> at
org.apache.hadoop.security.authentication.server.AuthenticationToken.<init>(AuthenticationToken.java:58)
[junit4] 2> at
org.apache.solr.security.HttpParamDelegationTokenPlugin$HttpParamDelegationTokenAuthenticationHandler$HttpParamAuthenticationHandler.authenticate(HttpParamDelegationTokenPlugin.java:213)
[junit4] 2> at
org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.authenticate(DelegationTokenAuthenticationHandler.java:348)
[junit4] 2> at
org.apache.solr.security.RequestContinuesRecorderAuthenticationHandler.authenticate(RequestContinuesRecorderAuthenticationHandler.java:69)
[junit4] 2> at
org.apache.hadoop.security.authentication.server.AuthenticationFilter.doFilter(AuthenticationFilter.java:519)
[junit4] 2> at
org.apache.solr.security.DelegationTokenKerberosFilter.doFilter(DelegationTokenKerberosFilter.java:128)
[junit4] 2> at
org.apache.solr.security.HttpParamDelegationTokenPlugin$HttpParamToRequestFilter.doFilter(HttpParamDelegationTokenPlugin.java:245)
[junit4] 2> at
org.apache.solr.security.KerberosPlugin.doAuthenticate(KerberosPlugin.java:243)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:404)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:310)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:296)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:113)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 549883 WARN (qtp1293387021-3464) [ ]
o.e.j.s.ServletHandler /solr/admin/collections
[junit4] 2> org.apache.solr.common.SolrException: Error during request
authentication,
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:410)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:310)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:296)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:113)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.lang.IllegalArgumentException: userName is
NULL, empty or contains a '&'
[junit4] 2> at
org.apache.hadoop.security.authentication.util.AuthToken.checkForIllegalArgument(AuthToken.java:87)
[junit4] 2> at
org.apache.hadoop.security.authentication.util.AuthToken.<init>(AuthToken.java:70)
[junit4] 2> at
org.apache.hadoop.security.authentication.server.AuthenticationToken.<init>(AuthenticationToken.java:58)
[junit4] 2> at
org.apache.solr.security.HttpParamDelegationTokenPlugin$HttpParamDelegationTokenAuthenticationHandler$HttpParamAuthenticationHandler.authenticate(HttpParamDelegationTokenPlugin.java:213)
[junit4] 2> at
org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.authenticate(DelegationTokenAuthenticationHandler.java:348)
[junit4] 2> at
org.apache.solr.security.RequestContinuesRecorderAuthenticationHandler.authenticate(RequestContinuesRecorderAuthenticationHandler.java:69)
[junit4] 2> at
org.apache.hadoop.security.authentication.server.AuthenticationFilter.doFilter(AuthenticationFilter.java:519)
[junit4] 2> at
org.apache.solr.security.DelegationTokenKerberosFilter.doFilter(DelegationTokenKerberosFilter.java:128)
[junit4] 2> at
org.apache.solr.security.HttpParamDelegationTokenPlugin$HttpParamToRequestFilter.doFilter(HttpParamDelegationTokenPlugin.java:245)
[junit4] 2> at
org.apache.solr.security.KerberosPlugin.doAuthenticate(KerberosPlugin.java:243)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.authenticateRequest(SolrDispatchFilter.java:404)
[junit4] 2> ... 26 more
[junit4] 2> 549884 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyNullProxyUser-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyNullProxyUser
[junit4] 2> 549913 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidGroup-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testProxyInvalidGroup
[junit4] 2> 549930 WARN (qtp1293387021-3462) [n:127.0.0.1:37474_solr ]
o.a.h.s.ShellBasedUnixGroupsMapping got exception trying to get groups for user
bar: id: invalid user name: "bar"
[junit4] 2>
[junit4] 2> 549930 WARN (qtp1293387021-3462) [n:127.0.0.1:37474_solr ]
o.a.h.s.UserGroupInformation No groups available for user bar
[junit4] 2> 549931 WARN
(TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidGroup-seed#[3CA79A4EDCCE81E0])
[ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie:
hadoop.auth="u=bogusGroup&p=bogusGroup&t=test&e=1483626604598&s=PopQtG0y51lYfn0RTYlj05BIcyU=";
Path=/; Domain=127.0.0.1; Expires= , 05- -2017 14:30:04 GMT; HttpOnly".
Invalid 'expires' attribute: , 05- -2017 14:30:04 GMT
[junit4] 2> 549932 INFO
(TEST-TestSolrCloudWithSecureImpersonation.testProxyInvalidGroup-seed#[3CA79A4EDCCE81E0])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testProxyInvalidGroup
[junit4] 2> 550843 INFO (jetty-launcher-581-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@3adffda6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 550843 INFO (jetty-launcher-581-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=947879515
[junit4] 2> 550844 INFO (jetty-launcher-581-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@2f3c19ca{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 550844 INFO (jetty-launcher-581-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=577120037
[junit4] 2> 551039 WARN
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@6d3fd2e9 name:
ZooKeeperConnection Watcher:127.0.0.1:47358/solr got event WatchedEvent
state:Expired type:None path:null path: null type: None
[junit4] 2> 551039 WARN
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ConnectionManager Our previous ZooKeeper
session was expired. Attempting to reconnect to recover relationship with
ZooKeeper...
[junit4] 2> 551040 WARN
(OverseerStateUpdate-97228592267198468-127.0.0.1:37474_solr-n_0000000000)
[n:127.0.0.1:37474_solr ] o.a.s.c.Overseer Solr cannot talk to ZK, exiting
Overseer main queue loop
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /overseer/queue
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:339)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:336)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:336)
[junit4] 2> at
org.apache.solr.cloud.DistributedQueue.fetchZkChildren(DistributedQueue.java:299)
[junit4] 2> at
org.apache.solr.cloud.DistributedQueue.firstChild(DistributedQueue.java:279)
[junit4] 2> at
org.apache.solr.cloud.DistributedQueue.firstElement(DistributedQueue.java:384)
[junit4] 2> at
org.apache.solr.cloud.DistributedQueue.peek(DistributedQueue.java:160)
[junit4] 2> at
org.apache.solr.cloud.DistributedQueue.peek(DistributedQueue.java:138)
[junit4] 2> at
org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:179)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 551041 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.Overseer Overseer
(id=97228592267198468-127.0.0.1:37474_solr-n_0000000000) closing
[junit4] 2> 551041 INFO
(OverseerStateUpdate-97228592267198468-127.0.0.1:37474_solr-n_0000000000)
[n:127.0.0.1:37474_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:37474_solr
[junit4] 2> 551042 WARN
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.DefaultConnectionStrategy Connection
expired - starting a new one...
[junit4] 2> 551044 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ConnectionManager Connection with
ZooKeeper reestablished.
[junit4] 2> 551044 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.ZkController ZooKeeper session
re-connected ... refreshing core states after session expiration.
[junit4] 2> 551047 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (0)
[junit4] 2> 551053 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:37474_solr
[junit4] 2> 551055 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.Overseer Overseer
(id=97228592267198476-127.0.0.1:37474_solr-n_0000000002) starting
[junit4] 2> 551068 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:37474_solr
[junit4] 2> 551074 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.DefaultConnectionStrategy Reconnected to
ZooKeeper
[junit4] 2> 551074 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ConnectionManager Connected:true
[junit4] 2> 551074 INFO
(coreZkRegister-1602-thread-1-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.ZkController Registering core
forwardingCollection_shard1_replica1 afterExpiration? true
[junit4] 2> 551075 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 551076 INFO
(zkCallback-591-thread-2-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/forwardingCollection/state.json] for collection
[forwardingCollection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 551076 INFO (Thread-848) [n:127.0.0.1:37474_solr ]
o.a.s.c.SolrCore config update listener called for core
forwardingCollection_shard1_replica1
[junit4] 2> 551077 INFO
(coreZkRegister-1602-thread-1-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1 r:core_node1
x:forwardingCollection_shard1_replica1] o.a.s.c.ActionThrottle The last leader
attempt started 22726ms ago.
[junit4] 2> 551079 INFO
(coreZkRegister-1602-thread-1-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1 r:core_node1
x:forwardingCollection_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I
may be the new leader - try and sync
[junit4] 2> 551079 INFO
(coreZkRegister-1602-thread-1-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1 r:core_node1
x:forwardingCollection_shard1_replica1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:37474/solr/forwardingCollection_shard1_replica1/
[junit4] 2> 551079 INFO
(coreZkRegister-1602-thread-1-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1 r:core_node1
x:forwardingCollection_shard1_replica1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 551079 INFO
(coreZkRegister-1602-thread-1-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1 r:core_node1
x:forwardingCollection_shard1_replica1] o.a.s.c.SyncStrategy
http://127.0.0.1:37474/solr/forwardingCollection_shard1_replica1/ has no
replicas
[junit4] 2> 551084 INFO
(coreZkRegister-1602-thread-1-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1 r:core_node1
x:forwardingCollection_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I am
the new leader:
http://127.0.0.1:37474/solr/forwardingCollection_shard1_replica1/ shard1
[junit4] 2> 551085 INFO
(coreZkRegister-1602-thread-1-processing-n:127.0.0.1:37474_solr)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1 r:core_node1
x:forwardingCollection_shard1_replica1] o.a.s.c.ZkController I am the leader,
no recovery necessary
[junit4] 2> 551141 ERROR (jetty-launcher-581-thread-1) [ ]
o.a.s.c.Overseer Could not create Overseer node
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /overseer
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:391)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:388)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:388)
[junit4] 2> at
org.apache.solr.cloud.Overseer.createOverseerNode(Overseer.java:728)
[junit4] 2> at
org.apache.solr.cloud.Overseer.getStateUpdateQueue(Overseer.java:601)
[junit4] 2> at
org.apache.solr.cloud.Overseer.getStateUpdateQueue(Overseer.java:588)
[junit4] 2> at
org.apache.solr.cloud.ZkController.publishNodeAsDown(ZkController.java:2407)
[junit4] 2> at
org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:654)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.destroy(SolrDispatchFilter.java:287)
[junit4] 2> at
org.eclipse.jetty.servlet.FilterHolder.destroyInstance(FilterHolder.java:172)
[junit4] 2> at
org.eclipse.jetty.servlet.FilterHolder.doStop(FilterHolder.java:150)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doStop(ServletHandler.java:238)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:142)
[junit4] 2> at
org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:160)
[junit4] 2> at
org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doStop(SessionHandler.java:127)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:142)
[junit4] 2> at
org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:160)
[junit4] 2> at
org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.stopContext(ContextHandler.java:825)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletContextHandler.stopContext(ServletContextHandler.java:356)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doStop(ContextHandler.java:880)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletContextHandler.doStop(ServletContextHandler.java:272)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:142)
[junit4] 2> at
org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:160)
[junit4] 2> at
org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.eclipse.jetty.util.component.ContainerLifeCycle.stop(ContainerLifeCycle.java:142)
[junit4] 2> at
org.eclipse.jetty.util.component.ContainerLifeCycle.doStop(ContainerLifeCycle.java:160)
[junit4] 2> at
org.eclipse.jetty.server.handler.AbstractHandler.doStop(AbstractHandler.java:73)
[junit4] 2> at
org.eclipse.jetty.server.Server.doStop(Server.java:493)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner.stop(JettySolrRunner.java:382)
[junit4] 2> at
org.apache.solr.cloud.MiniSolrCloudCluster.stopJettySolrRunner(MiniSolrCloudCluster.java:415)
[junit4] 2> at
org.apache.solr.cloud.MiniSolrCloudCluster.lambda$shutdown$1(MiniSolrCloudCluster.java:450)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 551142 WARN (jetty-launcher-581-thread-1) [ ]
o.a.s.c.ZkController Could not publish node as down:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /overseer
[junit4] 2> 551143 INFO (coreCloseExecutor-1623-thread-1)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1 r:core_node1
x:forwardingCollection_shard1_replica1] o.a.s.c.SolrCore
[forwardingCollection_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@cda5286
[junit4] 2> 551154 INFO (coreCloseExecutor-1623-thread-1)
[n:127.0.0.1:37474_solr c:forwardingCollection s:shard1 r:core_node1
x:forwardingCollection_shard1_replica1] o.a.s.m.SolrMetricManager Closing
metric reporters for: solr.core.forwardingCollection.shard1.replica1
[junit4] 2> 551154 INFO (jetty-launcher-581-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=97228592267198476-127.0.0.1:37474_solr-n_0000000002) closing
[junit4] 2> 551155 INFO
(OverseerStateUpdate-97228592267198476-127.0.0.1:37474_solr-n_0000000002)
[n:127.0.0.1:37474_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:37474_solr
[junit4] 2> 551161 INFO (jetty-launcher-581-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 551162 ERROR
(Thread[Thread-840,5,TGRP-TestSolrCloudWithSecureImpersonation])
[n:127.0.0.1:37474_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager
ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 5
[...truncated too long message...]
.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] 2> 584437 WARN
(jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358))
[n:127.0.0.1:52422_solr ] o.a.z.ClientCnxn Session 0x0 for server null,
unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native
Method)
[junit4] 2> at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 584937 WARN
(jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358))
[n:127.0.0.1:52422_solr ] o.a.z.c.StaticHostProvider Unexpected exception
[junit4] 2> java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] 2> 584938 WARN
(jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358))
[n:127.0.0.1:52422_solr ] o.a.z.ClientCnxn Session 0x0 for server null,
unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native
Method)
[junit4] 2> at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 585439 WARN
(jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358))
[n:127.0.0.1:52422_solr ] o.a.z.c.StaticHostProvider Unexpected exception
[junit4] 2> java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] 2> 585439 WARN
(jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358))
[n:127.0.0.1:52422_solr ] o.a.z.ClientCnxn Session 0x0 for server null,
unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native
Method)
[junit4] 2> at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 585940 WARN
(jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358))
[n:127.0.0.1:52422_solr ] o.a.z.c.StaticHostProvider Unexpected exception
[junit4] 2> java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] 2> 585940 WARN
(jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358))
[n:127.0.0.1:52422_solr ] o.a.z.ClientCnxn Session 0x0 for server null,
unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native
Method)
[junit4] 2> at
sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> Jan 05, 2017 4:30:41 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be
terminated:
[junit4] 2> 1) Thread[id=3590,
name=jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358),
state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=71, maxMBSortInHeap=7.518656450037901,
sim=RandomSimilarity(queryNorm=true): {}, locale=el, timezone=Etc/GMT-10
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_112
(64-bit)/cpus=3,threads=2,free=54032912,total=173170688
[junit4] 2> NOTE: All tests run in this JVM: [ResponseLogComponentTest,
DeleteInactiveReplicaTest, TestTolerantUpdateProcessorRandomCloud,
TestReversedWildcardFilterFactory, DistributedExpandComponentTest,
TestRangeQuery, TestBinaryResponseWriter, TestExportWriter, ZkSolrClientTest,
SolrIndexMetricsTest, TestSolrQueryParserDefaultOperatorResource,
DistributedFacetPivotSmallAdvancedTest, TestSubQueryTransformerCrossCore,
TestFuzzyAnalyzedSuggestions, TestFieldCache, SecurityConfHandlerTest,
StatsComponentTest, TestQuerySenderListener, UUIDUpdateProcessorFallbackTest,
HdfsUnloadDistributedZkTest, LeaderInitiatedRecoveryOnShardRestartTest,
EchoParamsTest, TestSolrQueryParser, TestUnifiedSolrHighlighter,
PreAnalyzedFieldManagedSchemaCloudTest, TestManagedSchemaAPI,
ResourceLoaderTest, SpellCheckCollatorTest, HdfsChaosMonkeyNothingIsSafeTest,
WordBreakSolrSpellCheckerTest, TestDocSet,
TestLMJelinekMercerSimilarityFactory, TestRawTransformer,
BasicDistributedZk2Test, TestSystemIdResolver,
UpdateRequestProcessorFactoryTest, OpenCloseCoreStressTest,
TestAuthenticationFramework, FieldAnalysisRequestHandlerTest,
SolrCoreMetricManagerTest, DataDrivenBlockJoinTest, TestChildDocTransformer,
ClassificationUpdateProcessorIntegrationTest, TestMiniSolrCloudClusterSSL,
TestSolrDynamicMBean, NotRequiredUniqueKeyTest, TestFoldingMultitermQuery,
TermVectorComponentDistributedTest, TestGraphMLResponseWriter,
SuggesterTSTTest, TestImpersonationWithHadoopAuth, TestRandomFlRTGCloud,
TestAtomicUpdateErrorCases, AsyncCallRequestStatusResponseTest,
ScriptEngineTest, OverseerModifyCollectionTest, PreAnalyzedUpdateProcessorTest,
CurrencyFieldOpenExchangeTest, SharedFSAutoReplicaFailoverTest,
TestUseDocValuesAsStored2, TestCustomSort, TestPushWriter, SuggesterTest,
TestRandomCollapseQParserPlugin, TestSolrCloudWithSecureImpersonation]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestSolrCloudWithSecureImpersonation -Dtests.seed=3CA79A4EDCCE81E0
-Dtests.slow=true -Dtests.locale=el -Dtests.timezone=Etc/GMT-10
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestSolrCloudWithSecureImpersonation (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.cloud.TestSolrCloudWithSecureImpersonation:
[junit4] > 1) Thread[id=3590,
name=jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358),
state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
[junit4] > at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
[junit4] > at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] > 2) Thread[id=3591,
name=jetty-launcher-581-thread-1-EventThread, state=WAITING,
group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at
org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)
[junit4] > at
__randomizedtesting.SeedInfo.seed([3CA79A4EDCCE81E0]:0)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=3590,
name=jetty-launcher-581-thread-1-SendThread(127.0.0.1:47358),
state=TIMED_WAITING, group=TGRP-TestSolrCloudWithSecureImpersonation]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at
org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
[junit4] > at
org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
[junit4] > at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
[junit4] > at
__randomizedtesting.SeedInfo.seed([3CA79A4EDCCE81E0]:0)
[junit4] Completed [121/675 (2!)] on J1 in 60.92s, 12 tests, 2 errors <<<
FAILURES!
[...truncated 64240 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
