Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/19809/
Java: 64bit/jdk-9-ea+171 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth
Error Message:
expected:<200> but was:<403>
Stack Trace:
java.lang.AssertionError: expected:<200> but was:<403>
at
__randomizedtesting.SeedInfo.seed([E00458113A038B69:5C6A2E039E500813]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at
org.apache.solr.security.BasicAuthIntegrationTest.executeCommand(BasicAuthIntegrationTest.java:242)
at
org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:154)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:563)
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:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
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
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:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 13168 lines...]
[junit4] Suite: org.apache.solr.security.BasicAuthIntegrationTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/init-core-data-001
[junit4] 2> 2169010 WARN
(SUITE-BasicAuthIntegrationTest-seed#[E00458113A038B69]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2346 numCloses=2346
[junit4] 2> 2169010 INFO
(SUITE-BasicAuthIntegrationTest-seed#[E00458113A038B69]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 2169011 INFO
(SUITE-BasicAuthIntegrationTest-seed#[E00458113A038B69]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 2169011 INFO
(SUITE-BasicAuthIntegrationTest-seed#[E00458113A038B69]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001
[junit4] 2> 2169011 INFO
(SUITE-BasicAuthIntegrationTest-seed#[E00458113A038B69]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2169011 INFO (Thread-4402) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2169011 INFO (Thread-4402) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 2169013 ERROR (Thread-4402) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 2169111 INFO
(SUITE-BasicAuthIntegrationTest-seed#[E00458113A038B69]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:32989
[junit4] 2> 2169115 INFO (jetty-launcher-3166-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2169115 INFO (jetty-launcher-3166-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2169115 INFO (jetty-launcher-3166-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2169123 INFO (jetty-launcher-3166-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2f9f04c0{/solr,null,AVAILABLE}
[junit4] 2> 2169123 INFO (jetty-launcher-3166-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2c542e7b{/solr,null,AVAILABLE}
[junit4] 2> 2169123 INFO (jetty-launcher-3166-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@681cb4dc{/solr,null,AVAILABLE}
[junit4] 2> 2169124 INFO (jetty-launcher-3166-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4d271c63{HTTP/1.1,[http/1.1]}{127.0.0.1:39589}
[junit4] 2> 2169124 INFO (jetty-launcher-3166-thread-1) [ ]
o.e.j.s.Server Started @2170712ms
[junit4] 2> 2169124 INFO (jetty-launcher-3166-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@2fc46199{HTTP/1.1,[http/1.1]}{127.0.0.1:39061}
[junit4] 2> 2169124 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39589}
[junit4] 2> 2169124 ERROR (jetty-launcher-3166-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2169125 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 2169125 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2169125 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2169125 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-08T06:28:40.420171Z
[junit4] 2> 2169124 INFO (jetty-launcher-3166-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@117f090e{HTTP/1.1,[http/1.1]}{127.0.0.1:40993}
[junit4] 2> 2169125 INFO (jetty-launcher-3166-thread-3) [ ]
o.e.j.s.Server Started @2170713ms
[junit4] 2> 2169125 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=40993}
[junit4] 2> 2169125 ERROR (jetty-launcher-3166-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2169125 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 2169126 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2169126 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2169126 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-08T06:28:40.421127Z
[junit4] 2> 2169124 INFO (jetty-launcher-3166-thread-2) [ ]
o.e.j.s.Server Started @2170712ms
[junit4] 2> 2169126 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=39061}
[junit4] 2> 2169126 ERROR (jetty-launcher-3166-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2169126 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 2169126 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2169126 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2169126 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-08T06:28:40.421931Z
[junit4] 2> 2169143 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2169146 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2169147 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2169148 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32989/solr
[junit4] 2> 2169163 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32989/solr
[junit4] 2> 2169172 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2169172 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32989/solr
[junit4] 2> 2169172 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39589_solr
[junit4] 2> 2169172 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98101055999639557-127.0.0.1:39589_solr-n_0000000000) starting
[junit4] 2> 2169178 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39589_solr
[junit4] 2> 2169178 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2169179 INFO (zkCallback-3179-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2169179 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2169179 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:39061_solr
[junit4] 2> 2169180 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2169194 INFO (zkCallback-3175-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2169194 INFO (zkCallback-3179-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2169194 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2169195 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:40993_solr
[junit4] 2> 2169197 INFO (zkCallback-3175-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2169197 INFO (zkCallback-3179-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2169202 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2169216 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39589.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2169218 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39589.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2169218 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39589.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2169219 INFO (jetty-launcher-3166-thread-1) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node1/.
[junit4] 2> 2169232 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40993.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2169234 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40993.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2169234 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40993.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2169235 INFO (jetty-launcher-3166-thread-3) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node3/.
[junit4] 2> 2169236 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39061.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2169238 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39061.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2169238 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39061.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2169239 INFO (jetty-launcher-3166-thread-2) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node2/.
[junit4] 2> 2169267 INFO
(SUITE-BasicAuthIntegrationTest-seed#[E00458113A038B69]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2169267 INFO
(SUITE-BasicAuthIntegrationTest-seed#[E00458113A038B69]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:32989/solr ready
[junit4] 2> 2169270 INFO (qtp144113290-19698) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 2169272 INFO (OverseerThreadFactory-9215-thread-1) [ ]
o.a.s.c.CreateCollectionCmd Create collection authCollection
[junit4] 2> 2169386 INFO (qtp1597741808-19692) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2169387 INFO (qtp1597741808-19692) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 2169387 INFO (qtp144113290-19682) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2169387 INFO (qtp144113290-19682) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 2169388 INFO (qtp179840030-19693) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2169388 INFO (qtp179840030-19693) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 2169491 INFO (zkCallback-3179-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2169491 INFO (zkCallback-3175-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2169491 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2170404 INFO (qtp1597741808-19692) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 2170404 INFO (qtp179840030-19693) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 2170404 INFO (qtp144113290-19682) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 7.0.0
[junit4] 2> 2170409 INFO (qtp144113290-19682) [ ] o.a.s.s.IndexSchema
[authCollection_shard3_replica_n1] Schema name=minimal
[junit4] 2> 2170409 INFO (qtp179840030-19693) [ ] o.a.s.s.IndexSchema
[authCollection_shard2_replica_n1] Schema name=minimal
[junit4] 2> 2170409 INFO (qtp1597741808-19692) [ ] o.a.s.s.IndexSchema
[authCollection_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2170410 INFO (qtp1597741808-19692) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2170410 INFO (qtp179840030-19693) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2170410 INFO (qtp1597741808-19692) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard1_replica_n1'
using configuration from collection authCollection, trusted=true
[junit4] 2> 2170410 INFO (qtp179840030-19693) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard2_replica_n1'
using configuration from collection authCollection, trusted=true
[junit4] 2> 2170410 INFO (qtp144113290-19682) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2170410 INFO (qtp144113290-19682) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard3_replica_n1'
using configuration from collection authCollection, trusted=true
[junit4] 2> 2170411 INFO (qtp1597741808-19692) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_40993.solr.core.authCollection.shard1.replica_n1' (registry
'solr.core.authCollection.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2170411 INFO (qtp179840030-19693) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39589.solr.core.authCollection.shard2.replica_n1' (registry
'solr.core.authCollection.shard2.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2170411 INFO (qtp1597741808-19692) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2170411 INFO (qtp144113290-19682) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_39061.solr.core.authCollection.shard3.replica_n1' (registry
'solr.core.authCollection.shard3.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2170411 INFO (qtp1597741808-19692) [ ] o.a.s.c.SolrCore
[[authCollection_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node3/authCollection_shard1_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node3/./authCollection_shard1_replica_n1/data/]
[junit4] 2> 2170411 INFO (qtp144113290-19682) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2170411 INFO (qtp179840030-19693) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 2170411 INFO (qtp144113290-19682) [ ] o.a.s.c.SolrCore
[[authCollection_shard3_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node2/authCollection_shard3_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node2/./authCollection_shard3_replica_n1/data/]
[junit4] 2> 2170411 INFO (qtp179840030-19693) [ ] o.a.s.c.SolrCore
[[authCollection_shard2_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node1/authCollection_shard2_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node1/./authCollection_shard2_replica_n1/data/]
[junit4] 2> 2170446 INFO (qtp1597741808-19692) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2170446 INFO (qtp1597741808-19692) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2170446 INFO (qtp144113290-19682) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2170446 INFO (qtp144113290-19682) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2170447 INFO (qtp1597741808-19692) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2170447 INFO (qtp1597741808-19692) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2170447 INFO (qtp144113290-19682) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2170447 INFO (qtp144113290-19682) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2170448 INFO (qtp1597741808-19692) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@557ae777[authCollection_shard1_replica_n1] main]
[junit4] 2> 2170448 INFO (qtp1597741808-19692) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2170448 INFO (qtp1597741808-19692) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2170448 INFO (qtp144113290-19682) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1c852068[authCollection_shard3_replica_n1] main]
[junit4] 2> 2170449 INFO (qtp1597741808-19692) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2170449 INFO (qtp144113290-19682) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2170449 INFO (qtp144113290-19682) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2170449 INFO (searcherExecutor-9222-thread-1) [ ]
o.a.s.c.SolrCore [authCollection_shard1_replica_n1] Registered new searcher
Searcher@557ae777[authCollection_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2170449 INFO (qtp1597741808-19692) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1569616897501036544
[junit4] 2> 2170449 INFO (qtp144113290-19682) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2170450 INFO (searcherExecutor-9224-thread-1) [ ]
o.a.s.c.SolrCore [authCollection_shard3_replica_n1] Registered new searcher
Searcher@1c852068[authCollection_shard3_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2170450 INFO (qtp144113290-19682) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1569616897502085120
[junit4] 2> 2170452 INFO (qtp179840030-19693) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2170452 INFO (qtp179840030-19693) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2170453 INFO (qtp179840030-19693) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2170453 INFO (qtp179840030-19693) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2170454 INFO (qtp179840030-19693) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@67ded7f6[authCollection_shard2_replica_n1] main]
[junit4] 2> 2170455 INFO (qtp1597741808-19692) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2170455 INFO (qtp1597741808-19692) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2170455 INFO (qtp1597741808-19692) [ ]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:40993/solr/authCollection_shard1_replica_n1/
[junit4] 2> 2170455 INFO (qtp1597741808-19692) [ ]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2170455 INFO (qtp1597741808-19692) [ ]
o.a.s.c.SyncStrategy
http://127.0.0.1:40993/solr/authCollection_shard1_replica_n1/ has no replicas
[junit4] 2> 2170455 INFO (qtp1597741808-19692) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2170456 INFO (qtp179840030-19693) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2170456 INFO (qtp179840030-19693) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2170457 INFO (qtp179840030-19693) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2170457 INFO (qtp1597741808-19692) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:40993/solr/authCollection_shard1_replica_n1/ shard1
[junit4] 2> 2170457 INFO (searcherExecutor-9223-thread-1) [ ]
o.a.s.c.SolrCore [authCollection_shard2_replica_n1] Registered new searcher
Searcher@67ded7f6[authCollection_shard2_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2170458 INFO (qtp179840030-19693) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1569616897510473728
[junit4] 2> 2170459 INFO (qtp144113290-19682) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2170459 INFO (qtp144113290-19682) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2170459 INFO (qtp144113290-19682) [ ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:39061/solr/authCollection_shard3_replica_n1/
[junit4] 2> 2170459 INFO (qtp144113290-19682) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 2170459 INFO (qtp144113290-19682) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:39061/solr/authCollection_shard3_replica_n1/ has no replicas
[junit4] 2> 2170459 INFO (qtp144113290-19682) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2170461 INFO (qtp144113290-19682) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:39061/solr/authCollection_shard3_replica_n1/ shard3
[junit4] 2> 2170461 INFO (qtp179840030-19693) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2170461 INFO (qtp179840030-19693) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2170461 INFO (qtp179840030-19693) [ ] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:39589/solr/authCollection_shard2_replica_n1/
[junit4] 2> 2170461 INFO (qtp179840030-19693) [ ] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 2170461 INFO (qtp179840030-19693) [ ] o.a.s.c.SyncStrategy
http://127.0.0.1:39589/solr/authCollection_shard2_replica_n1/ has no replicas
[junit4] 2> 2170461 INFO (qtp179840030-19693) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2170463 INFO (qtp179840030-19693) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:39589/solr/authCollection_shard2_replica_n1/ shard2
[junit4] 2> 2170565 INFO (zkCallback-3179-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2170565 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2170565 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2170608 INFO (qtp1597741808-19692) [ ]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2170609 INFO (qtp1597741808-19692) [ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1222
[junit4] 2> 2170612 INFO (qtp144113290-19682) [ ] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 2170613 INFO (qtp144113290-19682) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1225
[junit4] 2> 2170613 INFO (qtp179840030-19693) [ ] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 2170614 INFO (qtp179840030-19693) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n1&action=CREATE&numShards=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1225
[junit4] 2> 2170619 INFO (qtp144113290-19698) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 2170715 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2170715 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2170715 INFO (zkCallback-3179-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2171273 INFO
(OverseerCollectionConfigSetProcessor-98101055999639557-127.0.0.1:39589_solr-n_0000000000)
[ ] 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> 2171620 INFO (qtp144113290-19698) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2}
status=0 QTime=2349
[junit4] 2> 2171628 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testBasicAuth
[junit4] 2> 2171630 INFO (qtp144113290-19688) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/security/authentication params={} status=0
QTime=0
[junit4] 2> 2171631 INFO (qtp144113290-19688) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/security/authentication params={} status=0
QTime=0
[junit4] 2> 2171631 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 2171631 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 2171631 INFO (zkCallback-3179-thread-1) [ ]
o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 2171631 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.CoreContainer Initializing authorization plugin:
solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2171631 INFO (zkCallback-3179-thread-1) [ ]
o.a.s.c.CoreContainer Initializing authorization plugin:
solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2171631 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.CoreContainer Initializing authorization plugin:
solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2171638 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 2171638 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 2171638 INFO (zkCallback-3179-thread-1) [ ]
o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 2171641 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests
[junit4] 2> 2171642 INFO (zkCallback-3179-thread-1) [ ]
o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests
[junit4] 2> 2171642 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests
[junit4] 2> 2171682 INFO (qtp144113290-19682) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/security/authentication params={} status=0
QTime=0
[junit4] 2> 2171683 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@2fc46199{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2171684 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1096363031
[junit4] 2> 2171684 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 2171686 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 2171688 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 2171688 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 2171689 INFO (coreCloseExecutor-9237-thread-1) [ ]
o.a.s.c.SolrCore [authCollection_shard3_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@3cdd5a2d
[junit4] 2> 2171689 INFO (coreCloseExecutor-9237-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.authCollection.shard3.replica_n1, tag=1021139501
[junit4] 2> 2171692 INFO (coreCloseExecutor-9237-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.authCollection.shard3.leader, tag=1021139501
[junit4] 2> 2171693 INFO (zkCallback-3190-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 2171693 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 2171693 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 2171790 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2171790 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [2])
[junit4] 2> 2173193 WARN (zkCallback-3179-thread-1) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2173193 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2c542e7b{/solr,null,UNAVAILABLE}
[junit4] 2> 2173194 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2173196 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@5a9f918d{/solr,null,AVAILABLE}
[junit4] 2> 2173196 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@74671db5{HTTP/1.1,[http/1.1]}{127.0.0.1:45879}
[junit4] 2> 2173197 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.e.j.s.Server Started @2174784ms
[junit4] 2> 2173197 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=45879}
[junit4] 2> 2173197 ERROR
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 2173197 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.0.0
[junit4] 2> 2173197 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2173197 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2173197 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-08T06:28:44.492887Z
[junit4] 2> 2173200 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2173230 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:32989/solr
[junit4] 2> 2173234 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2173235 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2173236 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:45879_solr
[junit4] 2> 2173237 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2173237 INFO (zkCallback-3195-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2173237 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2173237 INFO (zkCallback-3190-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2173247 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.c.CoreContainer Initializing authorization plugin:
solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2173247 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 2173247 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests
[junit4] 2> 2173264 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45879.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2173269 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45879.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2173269 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45879.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2173271 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node2/.
[junit4] 2> 2173271 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [authCollection_shard3_replica_n1]
[junit4] 2> 2173271 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 2173373 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2173373 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2173373 INFO (zkCallback-3195-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2174276 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 2174281 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.s.IndexSchema [authCollection_shard3_replica_n1] Schema name=minimal
[junit4] 2> 2174282 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2174282 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard3_replica_n1'
using configuration from collection authCollection, trusted=true
[junit4] 2> 2174282 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_45879.solr.core.authCollection.shard3.replica_n1' (registry
'solr.core.authCollection.shard3.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@96aea3
[junit4] 2> 2174282 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2174282 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.c.SolrCore [[authCollection_shard3_replica_n1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node2/authCollection_shard3_replica_n1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001/tempDir-001/node2/./authCollection_shard3_replica_n1/data/]
[junit4] 2> 2174315 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 2174315 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2174316 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2174316 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2174316 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4db060c6[authCollection_shard3_replica_n1] main]
[junit4] 2> 2174317 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 2174317 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2174318 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2174318 INFO (searcherExecutor-9245-thread-1) [ ]
o.a.s.c.SolrCore [authCollection_shard3_replica_n1] Registered new searcher
Searcher@4db060c6[authCollection_shard3_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2174318 INFO (coreLoadExecutor-9244-thread-1) [ ]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1569616901557977088
[junit4] 2> 2174321 INFO (coreZkRegister-9239-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2174321 INFO (coreZkRegister-9239-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2174321 INFO (coreZkRegister-9239-thread-1) [ ]
o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:45879/solr/authCollection_shard3_replica_n1/
[junit4] 2> 2174321 INFO (coreZkRegister-9239-thread-1) [ ]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2174321 INFO (coreZkRegister-9239-thread-1) [ ]
o.a.s.c.SyncStrategy
http://127.0.0.1:45879/solr/authCollection_shard3_replica_n1/ has no replicas
[junit4] 2> 2174321 INFO (coreZkRegister-9239-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 2174322 INFO (coreZkRegister-9239-thread-1) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:45879/solr/authCollection_shard3_replica_n1/ shard3
[junit4] 2> 2174323 INFO (coreZkRegister-9239-thread-1) [ ]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2174424 INFO (zkCallback-3195-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2174424 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2174424 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/authCollection/state.json] for collection [authCollection]
has occurred - updating... (live nodes size: [3])
[junit4] 2> 2174794 INFO (qtp362526381-19776) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/security/authentication params={} status=0
QTime=0
[junit4] 2> 2174797 INFO (qtp179840030-19699) [ ]
o.a.s.s.RuleBasedAuthorizationPlugin request has come without principal. failed
permission {
[junit4] 2> "name":"security-edit",
[junit4] 2> "role":"admin"}
[junit4] 2> 2174797 INFO (qtp179840030-19699) [ ] o.a.s.s.HttpSolrCall
USER_REQUIRED auth header null context : userPrincipal: [null] type: [ADMIN],
collections: [], Path: [/____v2/cluster/security/authentication] path :
/____v2/cluster/security/authentication params :wt=javabin&version=2
[junit4] 2> 2174800 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.s.BasicAuthIntegrationTest Added Basic Auth security Header
c29scjpTb2xyUm9ja3M=
[junit4] 2> 2174800 INFO (qtp362526381-19779) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/security/authentication params={} status=0
QTime=0
[junit4] 2> 2174803 INFO (qtp362526381-19782) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/security/authentication params={} status=0
QTime=1
[junit4] 2> 2174803 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 2174803 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 2174803 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 2174803 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 2174803 INFO (zkCallback-3195-thread-1) [ ]
o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 2174803 INFO (zkCallback-3195-thread-1) [ ]
o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 2174803 INFO (qtp179840030-19693) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/security/authentication params={} status=0
QTime=0
[junit4] 2> 2174803 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.s.BasicAuthIntegrationTest Added Basic Auth security Header
c29scjpTb2xyUm9ja3M=
[junit4] 2> 2174804 INFO (qtp179840030-19679) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/security/authorization params={} status=0
QTime=0
[junit4] 2> 2174805 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 2174805 INFO (zkCallback-3195-thread-1) [ ]
o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 2174805 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.CoreContainer Initializing authorization plugin:
solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2174805 INFO (zkCallback-3195-thread-1) [ ]
o.a.s.c.CoreContainer Initializing authorization plugin:
solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2174805 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 2174805 INFO (zkCallback-3195-thread-1) [ ]
o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 2174805 INFO (qtp179840030-19699) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/cluster/security/authorization params={} status=0
QTime=0
[junit4] 2> 2174805 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.s.BasicAuthIntegrationTest Added Basic Auth security Header
aGFycnk6SGFycnlJc1ViZXJDb29s
[junit4] 2> 2174805 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 2174806 INFO (qtp179840030-19681) [ ]
o.a.s.s.RuleBasedAuthorizationPlugin This resource is configured to have a
permission {
[junit4] 2> "name":"security-edit",
[junit4] 2> "role":"admin"}, The principal [principal: harry] does not
have the right role
[junit4] 2> 2174806 INFO (qtp179840030-19681) [ ] o.a.s.s.HttpSolrCall
USER_REQUIRED auth header Basic aGFycnk6SGFycnlJc1ViZXJDb29s context :
userPrincipal: [[principal: harry]] type: [ADMIN], collections: [], Path:
[/____v2/cluster/security/authorization] path :
/____v2/cluster/security/authorization params :
[junit4] 2> 2174806 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.CoreContainer Initializing authorization plugin:
solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2174806 INFO (zkCallback-3175-thread-2) [ ]
o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 2174807 INFO
(TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[E00458113A038B69]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testBasicAuth
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=BasicAuthIntegrationTest -Dtests.method=testBasicAuth
-Dtests.seed=E00458113A038B69 -Dtests.multiplier=3 -Dtests.slow=true
-Dtests.locale=ln -Dtests.timezone=Etc/GMT+5 -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] FAILURE 3.19s J1 | BasicAuthIntegrationTest.testBasicAuth <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<200> but
was:<403>
[junit4] > at
__randomizedtesting.SeedInfo.seed([E00458113A038B69:5C6A2E039E500813]:0)
[junit4] > at
org.apache.solr.security.BasicAuthIntegrationTest.executeCommand(BasicAuthIntegrationTest.java:242)
[junit4] > at
org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:154)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at
java.base/java.lang.reflect.Method.invoke(Method.java:563)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 2174809 INFO (jetty-closer-3167-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@74671db5{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2174809 INFO (jetty-closer-3167-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@4d271c63{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2174809 INFO (jetty-closer-3167-thread-2) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1982124295
[junit4] 2> 2174810 INFO (jetty-closer-3167-thread-1) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=266038585
[junit4] 2> 2174810 INFO (jetty-closer-3167-thread-3) [ ]
o.e.j.s.AbstractConnector Stopped
ServerConnector@117f090e{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2174810 INFO (jetty-closer-3167-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 2174810 INFO (jetty-closer-3167-thread-3) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1935179744
[junit4] 2> 2174810 INFO (jetty-closer-3167-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 2174811 INFO (jetty-closer-3167-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 2174811 INFO (jetty-closer-3167-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 2174812 INFO (jetty-closer-3167-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 2174812 INFO (jetty-closer-3167-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 2174813 INFO (jetty-closer-3167-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 2174813 INFO (coreCloseExecutor-9250-thread-1) [ ]
o.a.s.c.SolrCore [authCollection_shard3_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@767f2860
[junit4] 2> 2174813 INFO (jetty-closer-3167-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 2174814 INFO (coreCloseExecutor-9250-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.authCollection.shard3.replica_n1, tag=1988044896
[junit4] 2> 2174814 INFO (jetty-closer-3167-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 2174814 INFO (coreCloseExecutor-9251-thread-1) [ ]
o.a.s.c.SolrCore [authCollection_shard2_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@140cb36
[junit4] 2> 2174815 INFO (coreCloseExecutor-9251-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.authCollection.shard2.replica_n1, tag=21023542
[junit4] 2> 2174816 INFO (jetty-closer-3167-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 2174817 INFO (jetty-closer-3167-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 2174818 INFO (jetty-closer-3167-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 2174818 INFO (coreCloseExecutor-9251-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.authCollection.shard2.leader, tag=21023542
[junit4] 2> 2174819 INFO (coreCloseExecutor-9252-thread-1) [ ]
o.a.s.c.SolrCore [authCollection_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@1d5526d8
[junit4] 2> 2174819 INFO (jetty-closer-3167-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98101055999639557-127.0.0.1:39589_solr-n_0000000000) closing
[junit4] 2> 2174819 INFO
(OverseerStateUpdate-98101055999639557-127.0.0.1:39589_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:39589_solr
[junit4] 2> 2174819 INFO (coreCloseExecutor-9250-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.authCollection.shard3.leader, tag=1988044896
[junit4] 2> 2174820 INFO (zkCallback-3184-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40993_solr
[junit4] 2> 2174820 INFO (zkCallback-3184-thread-2) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 2174821 INFO (coreCloseExecutor-9252-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.authCollection.shard1.replica_n1, tag=492119768
[junit4] 2> 2174840 INFO (coreCloseExecutor-9252-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.authCollection.shard1.leader, tag=492119768
[junit4] 2> 2174840 WARN (zkCallback-3184-thread-2) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2174841 INFO (jetty-closer-3167-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2f9f04c0{/solr,null,UNAVAILABLE}
[junit4] 2> 2176320 WARN (zkCallback-3175-thread-2) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2176320 WARN (zkCallback-3195-thread-1) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2176320 INFO (jetty-closer-3167-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@681cb4dc{/solr,null,UNAVAILABLE}
[junit4] 2> 2176320 INFO (jetty-closer-3167-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@5a9f918d{/solr,null,UNAVAILABLE}
[junit4] 2> 2176321 ERROR
(SUITE-BasicAuthIntegrationTest-seed#[E00458113A038B69]-worker) [ ]
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper
server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 2176322 INFO
(SUITE-BasicAuthIntegrationTest-seed#[E00458113A038B69]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:32989 32989
[junit4] 2> 2177102 INFO (Thread-4402) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:32989 32989
[junit4] 2> 2177102 WARN (Thread-4402) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 4 /solr/collections/authCollection/state.json
[junit4] 2> 2
/solr/overseer_elect/election/98101055999639557-127.0.0.1:39589_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/collections
[junit4] 2> 4 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.security.BasicAuthIntegrationTest_E00458113A038B69-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1943, maxMBSortInHeap=7.5969160495489,
sim=RandomSimilarity(queryNorm=false): {}, locale=ln, timezone=Etc/GMT+5
[junit4] 2> NOTE: Linux 4.10.0-21-generic amd64/Oracle Corporation 9-ea
(64-bit)/cpus=8,threads=1,free=156022032,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestManagedResource,
TestSSLRandomization, ConfigureRecoveryStrategyTest,
DocExpirationUpdateProcessorFactoryTest, SpellCheckCollatorTest,
TestBM25SimilarityFactory, TestNamedUpdateProcessors,
DistribDocExpirationUpdateProcessorTest, TestLeaderInitiatedRecoveryThread,
TermVectorComponentTest, TermsComponentTest, SolrSlf4jReporterTest,
SimpleCollectionCreateDeleteTest, TestCoreContainer, ChaosMonkeySafeLeaderTest,
TestFuzzyAnalyzedSuggestions, AtomicUpdateProcessorFactoryTest,
DirectSolrSpellCheckerTest, ShardRoutingCustomTest,
DistributedQueryComponentOptimizationTest, SimpleMLTQParserTest,
TestConfigSetsAPIZkFailure, TestUninvertingReader, ClusterStateUpdateTest,
TestFileDictionaryLookup, SmileWriterTest, TestSchemaVersionResource,
IndexBasedSpellCheckerTest, ConnectionReuseTest, BlobRepositoryCloudTest,
SpellCheckComponentTest, TestNumericRangeQuery64, TestMissingGroups,
TestPointFields, ManagedSchemaRoundRobinCloudTest, SolrIndexSplitterTest,
TestFieldCache, TestRawTransformer, TestFastLRUCache, InfixSuggestersTest,
TestPerFieldSimilarity, TestExportWriter, TestSimpleTrackingShardHandler,
MigrateRouteKeyTest, TestDelegationWithHadoopAuth, TestCollectionAPIs,
IndexSchemaRuntimeFieldTest, TestFilteredDocIdSet, DeleteShardTest,
DirectSolrConnectionTest, TestHashPartitioner, SimplePostToolTest,
TestReqParamsAPI, HLLUtilTest, BadCopyFieldTest, TestHdfsUpdateLog,
ShardRoutingTest, CdcrUpdateLogTest, TestBackupRepositoryFactory,
HighlighterMaxOffsetTest, ReplaceNodeTest, TestSQLHandlerNonCloud,
TestOverriddenPrefixQueryForCustomFieldType, TestCharFilters,
SystemInfoHandlerTest, MultiThreadedOCPTest,
ConcurrentDeleteAndCreateCollectionTest, HdfsChaosMonkeySafeLeaderTest,
DeleteLastCustomShardedReplicaTest, UninvertDocValuesMergePolicyTest,
TestSolr4Spatial2, TestComplexPhraseQParserPlugin, ZkControllerTest,
TestRTGBase, HttpSolrCallGetCoreTest, DistributedFacetPivotSmallTest,
OverseerRolesTest, TestSolrJ, FileUtilsTest, TestSizeLimitedDistributedMap,
SpatialFilterTest, WordBreakSolrSpellCheckerTest, PeerSyncReplicationTest,
TestSubQueryTransformer, TestStressVersions, TestSchemaNameResource,
TestLegacyTerms, TestElisionMultitermQuery, TestQueryUtils,
HdfsBasicDistributedZk2Test, JvmMetricsTest, TestCloudNestedDocsSort,
TestSystemCollAutoCreate, TestUpdate, AssignTest, TestScoreJoinQPScore,
TestLegacyFieldCache, DistributedFacetPivotLargeTest,
TestPostingsSolrHighlighter, PreAnalyzedUpdateProcessorTest, TestBulkSchemaAPI,
JsonLoaderTest, SubstringBytesRefFilterTest, TestWordDelimiterFilterFactory,
TestSolrIndexConfig, DistanceFunctionTest, TestRandomFlRTGCloud,
SortByFunctionTest, HighlighterTest, ChaosMonkeySafeLeaderWithPullReplicasTest,
TestNonDefinedSimilarityFactory, HttpPartitionTest, ResourceLoaderTest,
LeaderFailureAfterFreshStartTest, TestStressRecovery, SpatialHeatmapFacetsTest,
AnalyticsQueryTest, TestInitQParser, SolrGangliaReporterTest, TestConfigReload,
IndexSchemaTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest,
OpenCloseCoreStressTest, TestRandomFaceting, LeaderElectionTest,
DistributedSpellCheckComponentTest, TestRangeQuery, SolrCoreTest,
StatsComponentTest, QueryElevationComponentTest, ConvertedLegacyTest,
TestFiltering, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest,
DistributedQueryElevationComponentTest, SignatureUpdateProcessorFactoryTest,
TestExtendedDismaxParser, SuggesterFSTTest, TestFoldingMultitermQuery,
DocValuesTest, SuggesterTest, TestTrie, TestCSVLoader, PolyFieldTest,
NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest,
SolrCoreCheckLockOnStartupTest, FieldMutatingUpdateProcessorTest,
QueryEqualityTest, StatelessScriptUpdateProcessorFactoryTest, SolrInfoBeanTest,
XsltUpdateRequestHandlerTest, DebugComponentTest, TestSurroundQueryParser,
LukeRequestHandlerTest, TestQueryTypes, PrimitiveFieldTypeTest,
FileBasedSpellCheckerTest, RequestHandlersTest, DocumentBuilderTest,
PathHierarchyTokenizerFactoryTest, TestIndexingPerformance,
MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, LoggingHandlerTest,
SolrPluginUtilsTest, TestJmxIntegration, TestCollationField,
UpdateRequestProcessorFactoryTest, QueryParsingTest,
BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication,
TestPHPSerializedResponseWriter, PingRequestHandlerTest, SynonymTokenizerTest,
TestSweetSpotSimilarityFactory, TestFastWriter, TimeZoneUtilsTest,
ScriptEngineTest, URLClassifyProcessorTest, TestSystemIdResolver, DOMUtilTest,
TestLRUCache, TestDocumentBuilder, CircularListTest,
DistributedIntervalFacetingTest, TestPathTrie,
TestEmbeddedSolrServerConstructors, ActionThrottleTest,
AsyncCallRequestStatusResponseTest, CdcrBootstrapTest,
CollectionsAPIAsyncDistributedZkTest, ConfigSetsAPITest, ConnectionManagerTest,
CreateCollectionCleanupTest, DeleteReplicaTest, DistribCursorPagingTest,
DocValuesNotIndexedTest, ForceLeaderTest, SaslZkACLProviderTest,
ShardSplitTest, TestPullReplicaErrorHandling, TestReplicaProperties,
TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt,
TestSolrCloudWithSecureImpersonation, TestStressInPlaceUpdates,
HdfsChaosMonkeyNothingIsSafeTest, HdfsRecoverLeaseTest, HdfsRecoveryZkTest,
HdfsRestartWhileUpdatingTest, HdfsSyncSliceTest, HdfsThreadLeakTest,
HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest,
TestClusterStateMutator, ZkStateReaderTest, ZkStateWriterTest,
ImplicitSnitchTest, RulesTest, TestDynamicLoading, TestSolrCoreSnapshots,
RequestLoggingTest, TestReplicationHandlerBackup, CoreAdminRequestStatusTest,
CoreMergeIndexesAdminHandlerTest, InfoHandlerTest, MetricsHandlerTest,
SegmentsInfoRequestHandlerTest, TestApiFramework, TestCoreAdminApis,
DistributedDebugComponentTest, DistributedFacetExistsSmallTest,
DistributedSuggestComponentTest, ShufflingReplicaListTransformerTest,
SuggestComponentTest, TestDistributedStatsComponentCardinality,
SolrShardReporterTest, TestCustomDocTransformer, TestGraphMLResponseWriter,
TestSolrQueryResponse, TestChildDocTransformer,
TestDynamicFieldCollectionResource, TestFieldResource,
TestManagedStopFilterFactory, DateRangeFieldTest, SpatialRPTFieldTypeTest,
TestBulkSchemaConcurrent, TestCollationFieldDocValues,
TestHalfAndHalfDocValues, TestManagedSchema, TestManagedSchemaAPI,
TestManagedSchemaThreadSafety, TestUseDocValuesAsStored, LargeFieldTest,
MergeStrategyTest, TestPayloadScoreQParserPlugin, TestQueryWrapperFilter,
TestSearcherReuse, TestSolrFieldCacheBean, TestStandardQParsers,
TestStressUserVersions, TestJsonFacets, BasicAuthIntegrationTest]
[junit4] Completed [689/726 (1!)] on J1 in 8.10s, 1 test, 1 failure <<<
FAILURES!
[...truncated 36429 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
