This must've been before I reverted SOLR-13449. After reverting, this test passes.
On Tue, May 7, 2019 at 10:42 PM Apache Jenkins Server <[email protected]> wrote: > > Build: https://builds.apache.org/job/Lucene-Solr-Tests-8.1/42/ > > 2 tests failed. > FAILED: > org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed > > Error Message: > Expected metric minimums for prefix SECURITY./authentication/pki.: > {failMissingCredentials=0, authenticated=12, passThrough=0, > failWrongCredentials=0, requests=12, errors=0}, but got: > {failMissingCredentials=0, authenticated=4, passThrough=0, > totalTime=18761268, failWrongCredentials=0, requestTimes=352, requests=4, > errors=0} > > Stack Trace: > java.lang.AssertionError: Expected metric minimums for prefix > SECURITY./authentication/pki.: {failMissingCredentials=0, authenticated=12, > passThrough=0, failWrongCredentials=0, requests=12, errors=0}, but got: > {failMissingCredentials=0, authenticated=4, passThrough=0, > totalTime=18761268, failWrongCredentials=0, requestTimes=352, requests=4, > errors=0} > at > __randomizedtesting.SeedInfo.seed([D251F4D3C6FA0825:D479F6BDA44D9E96]:0) > at org.junit.Assert.fail(Assert.java:88) > at org.junit.Assert.assertTrue(Assert.java:41) > at > org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums(SolrCloudAuthTestCase.java:129) > at > org.apache.solr.cloud.SolrCloudAuthTestCase.assertPkiAuthMetricsMinimums(SolrCloudAuthTestCase.java:74) > at > org.apache.solr.security.JWTAuthPluginIntegrationTest.createCollectionUpdateAndQueryDistributed(JWTAuthPluginIntegrationTest.java:173) > 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:1750) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) > 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:947) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) > 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:368) > at java.lang.Thread.run(Thread.java:748) > > > FAILED: > org.apache.solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin.testBasics > > Error Message: > Expected metric minimums for prefix SECURITY./authentication.: > {failMissingCredentials=0, authenticated=8, passThrough=0, > failWrongCredentials=8, requests=16, errors=0}, but got: > {failMissingCredentials=0, authenticated=8, passThrough=0, > totalTime=579767495, failWrongCredentials=6, requestTimes=1521, requests=14, > errors=0} > > Stack Trace: > java.lang.AssertionError: Expected metric minimums for prefix > SECURITY./authentication.: {failMissingCredentials=0, authenticated=8, > passThrough=0, failWrongCredentials=8, requests=16, errors=0}, but got: > {failMissingCredentials=0, authenticated=8, passThrough=0, > totalTime=579767495, failWrongCredentials=6, requestTimes=1521, requests=14, > errors=0} > at > __randomizedtesting.SeedInfo.seed([D251F4D3C6FA0825:EF895AFFFE145655]:0) > at org.junit.Assert.fail(Assert.java:88) > at org.junit.Assert.assertTrue(Assert.java:41) > at > org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums(SolrCloudAuthTestCase.java:129) > at > org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums(SolrCloudAuthTestCase.java:83) > at > org.apache.solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin.testCollectionCreateSearchDelete(TestSolrCloudWithHadoopAuthPlugin.java:139) > at > org.apache.solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin.testBasics(TestSolrCloudWithHadoopAuthPlugin.java:108) > 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:1750) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) > 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:947) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) > 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:368) > at java.lang.Thread.run(Thread.java:748) > > > > > Build Log: > [...truncated 13255 lines...] > [junit4] Suite: > org.apache.solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin > [junit4] 2> 851548 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: > test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom > [junit4] 2> Creating dataDir: > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/init-core-data-001 > [junit4] 2> 851561 WARN > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2 > [junit4] 2> 851561 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) > w/NUMERIC_DOCVALUES_SYSPROP=false > [junit4] 2> 851562 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: > @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, > clientAuth=NaN) > [junit4] 2> 853462 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-002 > [junit4] 2> 853462 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER > [junit4] 2> 853467 INFO (ZkTestServer Run Thread) [ ] > o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 > [junit4] 2> 853467 INFO (ZkTestServer Run Thread) [ ] > o.a.s.c.ZkTestServer Starting server > [junit4] 2> 853564 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.c.ZkTestServer start zk server on port:39325 > [junit4] 2> 853564 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39325 > [junit4] 2> 853564 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39325 > [junit4] 2> 853636 WARN > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker-SendThread(lucene2-us-west.apache.org:39325)) > [ ] o.a.z.ClientCnxn SASL configuration failed: > javax.security.auth.login.LoginException: No JAAS configuration section named > 'Client' was found in specified JAAS configuration file: > '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/jaas-client.conf'. > Will continue connection to Zookeeper server without SASL authentication, if > Zookeeper server allows it. > [junit4] 2> 853715 WARN (zkConnectionManagerCallback-1476-thread-1) [ > ] o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@38e00e56 name: > ZooKeeperConnection Watcher:127.0.0.1:39325 got event WatchedEvent > state:AuthFailed type:None path:null path: null type: None > [junit4] 2> 853715 WARN (zkConnectionManagerCallback-1476-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed > [junit4] 2> 853724 INFO (zkConnectionManagerCallback-1476-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 853748 WARN > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker-SendThread(lucene2-us-west.apache.org:39325)) > [ ] o.a.z.ClientCnxn SASL configuration failed: > javax.security.auth.login.LoginException: No JAAS configuration section named > 'Client' was found in specified JAAS configuration file: > '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/jaas-client.conf'. > Will continue connection to Zookeeper server without SASL authentication, if > Zookeeper server allows it. > [junit4] 2> 853764 WARN (zkConnectionManagerCallback-1478-thread-1) [ > ] o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@55bcbfb name: > ZooKeeperConnection Watcher:127.0.0.1:39325/solr got event WatchedEvent > state:AuthFailed type:None path:null path: null type: None > [junit4] 2> 853764 WARN (zkConnectionManagerCallback-1478-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed > [junit4] 2> 853784 INFO (zkConnectionManagerCallback-1478-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 853785 WARN > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker-SendThread(lucene2-us-west.apache.org:39325)) > [ ] o.a.z.ClientCnxn SASL configuration failed: > javax.security.auth.login.LoginException: No JAAS configuration section named > 'Client' was found in specified JAAS configuration file: > '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/jaas-client.conf'. > Will continue connection to Zookeeper server without SASL authentication, if > Zookeeper server allows it. > [junit4] 2> 853800 WARN (zkConnectionManagerCallback-1480-thread-1) [ > ] o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@1cd36c29 name: > ZooKeeperConnection Watcher:127.0.0.1:39325 got event WatchedEvent > state:AuthFailed type:None path:null path: null type: None > [junit4] 2> 853800 WARN (zkConnectionManagerCallback-1480-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed > [junit4] 2> 853801 INFO (zkConnectionManagerCallback-1480-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 853818 WARN (jetty-launcher-1481-thread-1) [ ] > o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time > [junit4] 2> 853819 INFO (jetty-launcher-1481-thread-1) [ ] > o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) > [junit4] 2> 853819 INFO (jetty-launcher-1481-thread-1) [ ] > o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... > [junit4] 2> 853819 INFO (jetty-launcher-1481-thread-1) [ ] > o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: > c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 > [junit4] 2> 853857 INFO (jetty-launcher-1481-thread-1) [ ] > o.e.j.s.session DefaultSessionIdManager workerName=node0 > [junit4] 2> 853857 INFO (jetty-launcher-1481-thread-1) [ ] > o.e.j.s.session No SessionScavenger set, using defaults > [junit4] 2> 853857 INFO (jetty-launcher-1481-thread-1) [ ] > o.e.j.s.session node0 Scavenging every 600000ms > [junit4] 2> 853860 INFO (jetty-launcher-1481-thread-1) [ ] > o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@2373f6d2{/solr,null,AVAILABLE} > [junit4] 2> 853864 INFO (jetty-launcher-1481-thread-1) [ ] > o.e.j.s.AbstractConnector Started > ServerConnector@21797c98{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:36456} > [junit4] 2> 853864 INFO (jetty-launcher-1481-thread-1) [ ] > o.e.j.s.Server Started @854259ms > [junit4] 2> 853864 INFO (jetty-launcher-1481-thread-1) [ ] > o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, > hostPort=36456} > [junit4] 2> 853865 ERROR (jetty-launcher-1481-thread-1) [ ] > o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be > missing or incomplete. > [junit4] 2> 853865 INFO (jetty-launcher-1481-thread-1) [ ] > o.a.s.s.SolrDispatchFilter Using logger factory > org.apache.logging.slf4j.Log4jLoggerFactory > [junit4] 2> 853865 INFO (jetty-launcher-1481-thread-1) [ ] > o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version > 8.1.0 > [junit4] 2> 853865 INFO (jetty-launcher-1481-thread-1) [ ] > o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port > null > [junit4] 2> 853865 INFO (jetty-launcher-1481-thread-1) [ ] > o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null > [junit4] 2> 853901 INFO (jetty-launcher-1481-thread-1) [ ] > o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: > 2019-05-07T15:09:12.331Z > [junit4] 2> 853954 WARN > (jetty-launcher-1481-thread-1-SendThread(lucene2-us-west.apache.org:39325)) [ > ] o.a.z.ClientCnxn SASL configuration failed: > javax.security.auth.login.LoginException: No JAAS configuration section named > 'Client' was found in specified JAAS configuration file: > '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/jaas-client.conf'. > Will continue connection to Zookeeper server without SASL authentication, if > Zookeeper server allows it. > [junit4] 2> 853970 WARN (zkConnectionManagerCallback-1483-thread-1) [ > ] o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@10ba3824 name: > ZooKeeperConnection Watcher:127.0.0.1:39325/solr got event WatchedEvent > state:AuthFailed type:None path:null path: null type: None > [junit4] 2> 853970 WARN (zkConnectionManagerCallback-1483-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed > [junit4] 2> 853970 INFO (zkConnectionManagerCallback-1483-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 853975 INFO (jetty-launcher-1481-thread-1) [ ] > o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... > [junit4] 2> 854540 INFO (jetty-launcher-1481-thread-1) [ ] > o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: > WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] > [junit4] 2> 854555 WARN (jetty-launcher-1481-thread-1) [ ] > o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for > SslContextFactory@4be5c573[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 854575 WARN (jetty-launcher-1481-thread-1) [ ] > o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for > SslContextFactory@154bf116[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 854576 INFO (jetty-launcher-1481-thread-1) [ ] > o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39325/solr > [junit4] 2> 854576 WARN > (jetty-launcher-1481-thread-1-SendThread(lucene2-us-west.apache.org:39325)) [ > ] o.a.z.ClientCnxn SASL configuration failed: > javax.security.auth.login.LoginException: No JAAS configuration section named > 'Client' was found in specified JAAS configuration file: > '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/jaas-client.conf'. > Will continue connection to Zookeeper server without SASL authentication, if > Zookeeper server allows it. > [junit4] 2> 854577 WARN (zkConnectionManagerCallback-1490-thread-1) [ > ] o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@74da40c9 name: > ZooKeeperConnection Watcher:127.0.0.1:39325 got event WatchedEvent > state:AuthFailed type:None path:null path: null type: None > [junit4] 2> 854577 WARN (zkConnectionManagerCallback-1490-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed > [junit4] 2> 854591 INFO (zkConnectionManagerCallback-1490-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 854592 WARN > (jetty-launcher-1481-thread-1-SendThread(lucene2-us-west.apache.org:39325)) [ > ] o.a.z.ClientCnxn SASL configuration failed: > javax.security.auth.login.LoginException: No JAAS configuration section named > 'Client' was found in specified JAAS configuration file: > '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/jaas-client.conf'. > Will continue connection to Zookeeper server without SASL authentication, if > Zookeeper server allows it. > [junit4] 2> 854659 WARN (zkConnectionManagerCallback-1492-thread-1) [ > ] o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@2b200736 name: > ZooKeeperConnection Watcher:127.0.0.1:39325/solr got event WatchedEvent > state:AuthFailed type:None path:null path: null type: None > [junit4] 2> 854659 WARN (zkConnectionManagerCallback-1492-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed > [junit4] 2> 854659 INFO (zkConnectionManagerCallback-1492-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 855397 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.c.OverseerElectionContext I am going to be > the leader 127.0.0.1:36456_solr > [junit4] 2> 855450 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.c.Overseer Overseer > (id=74651453398122501-127.0.0.1:36456_solr-n_0000000000) starting > [junit4] 2> 855538 WARN > (jetty-launcher-1481-thread-1-SendThread(lucene2-us-west.apache.org:39325)) [ > ] o.a.z.ClientCnxn SASL configuration failed: > javax.security.auth.login.LoginException: No JAAS configuration section named > 'Client' was found in specified JAAS configuration file: > '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/jaas-client.conf'. > Will continue connection to Zookeeper server without SASL authentication, if > Zookeeper server allows it. > [junit4] 2> 855558 WARN (zkConnectionManagerCallback-1499-thread-1) [ > ] o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@70ed8ca8 name: > ZooKeeperConnection Watcher:127.0.0.1:39325/solr got event WatchedEvent > state:AuthFailed type:None path:null path: null type: None > [junit4] 2> 855559 WARN (zkConnectionManagerCallback-1499-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed > [junit4] 2> 855559 INFO (zkConnectionManagerCallback-1499-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 855576 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster > at 127.0.0.1:39325/solr ready > [junit4] 2> 855577 INFO > (OverseerStateUpdate-74651453398122501-127.0.0.1:36456_solr-n_0000000000) > [n:127.0.0.1:36456_solr ] o.a.s.c.Overseer Starting to work on the main > queue : 127.0.0.1:36456_solr > [junit4] 2> 855577 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.c.ZkController Register node as live in > ZooKeeper:/live_nodes/127.0.0.1:36456_solr > [junit4] 2> 855592 INFO > (OverseerStateUpdate-74651453398122501-127.0.0.1:36456_solr-n_0000000000) > [n:127.0.0.1:36456_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from > ZooKeeper... (0) -> (1) > [junit4] 2> 855593 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.c.CoreContainer Initializing > authentication plugin: > org.apache.solr.security.ConfigurableInternodeAuthHadoopPlugin > [junit4] 2> 855754 INFO (zkCallback-1498-thread-1) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) > [junit4] 2> 855756 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder Setting up > SPNego auth with config: > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/jaas-client.conf > [junit4] 2> 855773 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.s.HadoopAuthPlugin Params: > {kerberos.principal=HTTP/127.0.0.1, > kerberos.keytab=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/keytabs, > type=kerberos, kerberos.name.rules=RULE:[1:$1@$0](.*EXAMPLE.COM)s/@.*// > [junit4] 2> RULE:[2:$2@$0](.*EXAMPLE.COM)s/@.*// > [junit4] 2> DEFAULT, > delegation-token.json-mapper.AUTO_CLOSE_TARGET=false} > [junit4] 2> 856013 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.c.s.i.Krb5HttpClientBuilder Setting up > SPNego auth with config: > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/jaas-client.conf > [junit4] 2> 856110 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.h.a.MetricsHistoryHandler No .system > collection, keeping metrics history in memory. > [junit4] 2> 856262 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_36456.solr.node' (registry 'solr.node') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@2d3ec300 > [junit4] 2> 856296 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_36456.solr.jvm' (registry 'solr.jvm') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@2d3ec300 > [junit4] 2> 856296 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_36456.solr.jetty' (registry 'solr.jetty') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@2d3ec300 > [junit4] 2> 856297 INFO (jetty-launcher-1481-thread-1) > [n:127.0.0.1:36456_solr ] o.a.s.c.CorePropertiesLocator Found 0 core > definitions underneath > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-002/node1/. > [junit4] 2> 856401 WARN > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker-SendThread(lucene2-us-west.apache.org:39325)) > [ ] o.a.z.ClientCnxn SASL configuration failed: > javax.security.auth.login.LoginException: No JAAS configuration section named > 'Client' was found in specified JAAS configuration file: > '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/jaas-client.conf'. > Will continue connection to Zookeeper server without SASL authentication, if > Zookeeper server allows it. > [junit4] 2> 856402 WARN (zkConnectionManagerCallback-1505-thread-1) [ > ] o.a.s.c.c.ConnectionManager Watcher > org.apache.solr.common.cloud.ConnectionManager@137ad575 name: > ZooKeeperConnection Watcher:127.0.0.1:39325/solr got event WatchedEvent > state:AuthFailed type:None path:null path: null type: None > [junit4] 2> 856402 WARN (zkConnectionManagerCallback-1505-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient received AuthFailed > [junit4] 2> 856402 INFO (zkConnectionManagerCallback-1505-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 856417 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) > [junit4] 2> 856418 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39325/solr > ready > [junit4] 2> 856506 INFO > (TEST-TestSolrCloudWithHadoopAuthPlugin.testBasics-seed#[D251F4D3C6FA0825]) [ > ] o.a.s.SolrTestCaseJ4 ###Starting testBasics > [junit4] 1> Debug is true storeKey true useTicketCache false useKeyTab > true doNotPrompt false ticketCache is null isInitiator true KeyTab is > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/keytabs > refreshKrb5Config is true principal is solr tryFirstPass is false > useFirstPass is false storePass is false clearPass is false > [junit4] 1> Refreshing Kerberos configuration > [junit4] 2> 857098 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.r.KdcRequest The preauth data is empty. > [junit4] 2> 857137 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.KdcHandler KRB error occurred while processing request:Additional > pre-authentication required > [junit4] 2> 857295 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.r.AsRequest AS_REQ ISSUE: authtime 1557241755722,[email protected] > for krbtgt/[email protected] > [junit4] 1> principal is [email protected] > [junit4] 1> Will use keytab > [junit4] 1> Commit Succeeded > [junit4] 1> > [junit4] 2> 857591 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.r.TgsRequest TGS_REQ ISSUE: authtime 1557241755988,solr for > HTTP/[email protected] > [junit4] 2> 857743 INFO (qtp722099044-4908) [n:127.0.0.1:36456_solr > ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params > collection.configName=conf1&name=testkerberoscollection&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 > and sendToOCPQueue=true > [junit4] 2> 857795 INFO > (OverseerThreadFactory-1875-thread-1-processing-n:127.0.0.1:36456_solr) > [n:127.0.0.1:36456_solr ] o.a.s.c.a.c.CreateCollectionCmd Create > collection testkerberoscollection > [junit4] 1> Debug is true storeKey true useTicketCache false useKeyTab > true doNotPrompt false ticketCache is null isInitiator true KeyTab is > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/keytabs > refreshKrb5Config is true principal is solr tryFirstPass is false > useFirstPass is false storePass is false clearPass is false > [junit4] 1> Refreshing Kerberos configuration > [junit4] 2> 858026 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.r.KdcRequest The preauth data is empty. > [junit4] 2> 858026 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.KdcHandler KRB error occurred while processing request:Additional > pre-authentication required > [junit4] 2> 858269 INFO (pool-20-thread-2) [ ] > o.a.k.k.k.s.r.AsRequest AS_REQ ISSUE: authtime 1557241756590,[email protected] > for krbtgt/[email protected] > [junit4] 1> principal is [email protected] > [junit4] 1> Will use keytab > [junit4] 1> Commit Succeeded > [junit4] 1> > [junit4] 2> 858417 INFO (pool-20-thread-2) [ ] > o.a.k.k.k.s.r.TgsRequest TGS_REQ ISSUE: authtime 1557241756846,solr for > HTTP/[email protected] > [junit4] 2> 858711 INFO (qtp722099044-4905) [n:127.0.0.1:36456_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} > status=0 QTime=0 > [junit4] 2> 858741 INFO > (OverseerStateUpdate-74651453398122501-127.0.0.1:36456_solr-n_0000000000) > [n:127.0.0.1:36456_solr ] o.a.s.c.o.SliceMutator createReplica() { > [junit4] 2> "operation":"ADDREPLICA", > [junit4] 2> "collection":"testkerberoscollection", > [junit4] 2> "shard":"shard1", > [junit4] 2> "core":"testkerberoscollection_shard1_replica_n1", > [junit4] 2> "state":"down", > [junit4] 2> "base_url":"http://127.0.0.1:36456/solr";, > [junit4] 2> "type":"NRT", > [junit4] 2> "waitForFinalState":"false"} > [junit4] 1> Debug is true storeKey true useTicketCache false useKeyTab > true doNotPrompt false ticketCache is null isInitiator true KeyTab is > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/keytabs > refreshKrb5Config is true principal is solr tryFirstPass is false > useFirstPass is false storePass is false clearPass is false > [junit4] 1> Refreshing Kerberos configuration > [junit4] 2> 858977 INFO (pool-20-thread-2) [ ] > o.a.k.k.k.s.r.KdcRequest The preauth data is empty. > [junit4] 2> 858978 INFO (pool-20-thread-2) [ ] > o.a.k.k.k.s.KdcHandler KRB error occurred while processing request:Additional > pre-authentication required > [junit4] 2> 859018 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.r.AsRequest AS_REQ ISSUE: authtime 1557241757448,[email protected] > for krbtgt/[email protected] > [junit4] 1> principal is [email protected] > [junit4] 1> Will use keytab > [junit4] 1> Commit Succeeded > [junit4] 1> > [junit4] 2> 859087 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.r.TgsRequest TGS_REQ ISSUE: authtime 1557241757517,solr for > HTTP/[email protected] > [junit4] 2> 859160 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > x:testkerberoscollection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core > create command > qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=testkerberoscollection_shard1_replica_n1&action=CREATE&numShards=1&collection=testkerberoscollection&shard=shard1&wt=javabin&version=2&replicaType=NRT > [junit4] 2> 859160 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > x:testkerberoscollection_shard1_replica_n1] > o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for > 2147483647 transient cores > [junit4] 2> 860214 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene > MatchVersion: 8.1.0 > [junit4] 2> 860251 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.s.IndexSchema > [testkerberoscollection_shard1_replica_n1] Schema name=minimal > [junit4] 2> 860262 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema > minimal/1.1 with uniqueid field id > [junit4] 2> 860262 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.CoreContainer Creating > SolrCore 'testkerberoscollection_shard1_replica_n1' using configuration from > collection testkerberoscollection, trusted=true > [junit4] 2> 860264 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX > monitoring for > 'solr_36456.solr.core.testkerberoscollection.shard1.replica_n1' (registry > 'solr.core.testkerberoscollection.shard1.replica_n1') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@2d3ec300 > [junit4] 2> 860264 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.SolrCore > [[testkerberoscollection_shard1_replica_n1] ] Opening new SolrCore at > [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-002/node1/testkerberoscollection_shard1_replica_n1], > > dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-002/node1/./testkerberoscollection_shard1_replica_n1/data/] > [junit4] 2> 860480 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.u.UpdateHandler Using > UpdateLog implementation: org.apache.solr.update.UpdateLog > [junit4] 2> 860480 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing > UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 > maxNumLogsToKeep=10 numVersionBuckets=65536 > [junit4] 2> 860481 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.u.CommitTracker Hard > AutoCommit: disabled > [junit4] 2> 860481 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.u.CommitTracker Soft > AutoCommit: disabled > [junit4] 2> 860482 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening > [Searcher@3b478112[testkerberoscollection_shard1_replica_n1] main] > [junit4] 2> 860509 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage > Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 > [junit4] 2> 860509 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage > Loaded null at path _rest_managed.json using > ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 860509 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.h.ReplicationHandler > Commits will be reserved for 10000ms. > [junit4] 2> 860510 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find > max version in index or recent updates, using new clock 1632886334622269440 > [junit4] 2> 860531 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful > update of terms at /collections/testkerberoscollection/terms/shard1 to > Terms{values={core_node2=0}, version=0} > [junit4] 2> 860531 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] > o.a.s.c.ShardLeaderElectionContextBase make sure parent is created > /collections/testkerberoscollection/leaders/shard1 > [junit4] 2> 860537 INFO > (searcherExecutor-1880-thread-1-processing-n:127.0.0.1:36456_solr > x:testkerberoscollection_shard1_replica_n1 c:testkerberoscollection s:shard1 > r:core_node2) [n:127.0.0.1:36456_solr c:testkerberoscollection s:shard1 > r:core_node2 x:testkerberoscollection_shard1_replica_n1] o.a.s.c.SolrCore > [testkerberoscollection_shard1_replica_n1] Registered new searcher > Searcher@3b478112[testkerberoscollection_shard1_replica_n1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 860568 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] > o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. > [junit4] 2> 860568 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] > o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync > [junit4] 2> 860568 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync > replicas to > http://127.0.0.1:36456/solr/testkerberoscollection_shard1_replica_n1/ > [junit4] 2> 860568 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success > - now sync replicas to me > [junit4] 2> 860568 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.SyncStrategy > http://127.0.0.1:36456/solr/testkerberoscollection_shard1_replica_n1/ has no > replicas > [junit4] 2> 860568 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] > o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node > /collections/testkerberoscollection/leaders/shard1/leader after winning as > /collections/testkerberoscollection/leader_elect/shard1/election/74651453398122501-core_node2-n_0000000000 > [junit4] 2> 860582 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] > o.a.s.c.ShardLeaderElectionContext I am the new leader: > http://127.0.0.1:36456/solr/testkerberoscollection_shard1_replica_n1/ shard1 > [junit4] 2> 860743 INFO (zkCallback-1491-thread-1) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testkerberoscollection/state.json] for collection > [testkerberoscollection] has occurred - updating... (live nodes size: [1]) > [junit4] 2> 860744 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.ZkController I am the > leader, no recovery necessary > [junit4] 2> 860746 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] > webapp=null path=/admin/cores > params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=testkerberoscollection_shard1_replica_n1&action=CREATE&numShards=1&collection=testkerberoscollection&shard=shard1&wt=javabin&version=2&replicaType=NRT} > status=0 QTime=1586 > [junit4] 2> 860791 INFO (zkCallback-1491-thread-2) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testkerberoscollection/state.json] for collection > [testkerberoscollection] has occurred - updating... (live nodes size: [1]) > [junit4] 2> 860858 INFO (qtp722099044-4908) [n:127.0.0.1:36456_solr > ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at > most 45 seconds. Check all shard replicas > [junit4] 2> 860966 INFO (zkCallback-1491-thread-1) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testkerberoscollection/state.json] for collection > [testkerberoscollection] has occurred - updating... (live nodes size: [1]) > [junit4] 2> 860966 INFO (zkCallback-1491-thread-2) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testkerberoscollection/state.json] for collection > [testkerberoscollection] has occurred - updating... (live nodes size: [1]) > [junit4] 2> 860967 INFO (qtp722099044-4908) [n:127.0.0.1:36456_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections > params={collection.configName=conf1&name=testkerberoscollection&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} > status=0 QTime=3223 > [junit4] 2> 860974 INFO (zkCallback-1491-thread-3) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testkerberoscollection/state.json] for collection > [testkerberoscollection] has occurred - updating... (live nodes size: [1]) > [junit4] 1> Debug is true storeKey true useTicketCache false useKeyTab > true doNotPrompt false ticketCache is null isInitiator true KeyTab is > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/keytabs > refreshKrb5Config is true principal is solr tryFirstPass is false > useFirstPass is false storePass is false clearPass is false > [junit4] 1> Refreshing Kerberos configuration > [junit4] 2> 861121 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.r.KdcRequest The preauth data is empty. > [junit4] 2> 861122 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.KdcHandler KRB error occurred while processing request:Additional > pre-authentication required > [junit4] 1> principal is [email protected] > [junit4] 1> Will use keytab > [junit4] 1> Commit Succeeded > [junit4] 1> > [junit4] 2> 861158 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.r.AsRequest AS_REQ ISSUE: authtime 1557241759587,[email protected] > for krbtgt/[email protected] > [junit4] 2> 861223 INFO (pool-20-thread-2) [ ] > o.a.k.k.k.s.r.TgsRequest TGS_REQ ISSUE: authtime 1557241759653,solr for > HTTP/[email protected] > [junit4] 2> 861541 INFO (qtp722099044-4905) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful > update of terms at /collections/testkerberoscollection/terms/shard1 to > Terms{values={core_node2=1}, version=1} > [junit4] 2> 861541 INFO (qtp722099044-4905) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] > o.a.s.u.p.LogUpdateProcessorFactory > [testkerberoscollection_shard1_replica_n1] webapp=/solr path=/update > params={wt=javabin&version=2}{add=[1 (1632886335510413312)]} 0 183 > [junit4] 1> Debug is true storeKey true useTicketCache false useKeyTab > true doNotPrompt false ticketCache is null isInitiator true KeyTab is > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/keytabs > refreshKrb5Config is true principal is solr tryFirstPass is false > useFirstPass is false storePass is false clearPass is false > [junit4] 1> Refreshing Kerberos configuration > [junit4] 2> 861612 INFO (pool-20-thread-2) [ ] > o.a.k.k.k.s.r.KdcRequest The preauth data is empty. > [junit4] 2> 861612 INFO (pool-20-thread-2) [ ] > o.a.k.k.k.s.KdcHandler KRB error occurred while processing request:Additional > pre-authentication required > [junit4] 2> 861721 INFO (pool-20-thread-2) [ ] > o.a.k.k.k.s.r.AsRequest AS_REQ ISSUE: authtime 1557241760151,[email protected] > for krbtgt/[email protected] > [junit4] 1> principal is [email protected] > [junit4] 1> Will use keytab > [junit4] 1> Commit Succeeded > [junit4] 1> > [junit4] 2> 861795 INFO (pool-20-thread-2) [ ] > o.a.k.k.k.s.r.TgsRequest TGS_REQ ISSUE: authtime 1557241760224,solr for > HTTP/[email protected] > [junit4] 2> 861974 INFO > (OverseerCollectionConfigSetProcessor-74651453398122501-127.0.0.1:36456_solr-n_0000000000) > [n:127.0.0.1:36456_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> 862052 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 > start > commit{_version_=1632886336239173632,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} > [junit4] 2> 862052 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.u.SolrIndexWriter Calling > setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4afe318f > commitCommandVersion:1632886336239173632 > [junit4] 2> 862054 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening > [Searcher@28f166d[testkerberoscollection_shard1_replica_n1] main] > [junit4] 2> 862064 INFO > (searcherExecutor-1880-thread-1-processing-n:127.0.0.1:36456_solr > x:testkerberoscollection_shard1_replica_n1 c:testkerberoscollection s:shard1 > r:core_node2) [n:127.0.0.1:36456_solr c:testkerberoscollection s:shard1 > r:core_node2 x:testkerberoscollection_shard1_replica_n1] o.a.s.c.SolrCore > [testkerberoscollection_shard1_replica_n1] Registered new searcher > Searcher@28f166d[testkerberoscollection_shard1_replica_n1] > main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.1.0):C1:[diagnostics={os=Linux, > java.vendor=Oracle Corporation, java.version=1.8.0_191, > java.vm.version=25.191-b12, lucene.version=8.1.0, os.arch=amd64, > java.runtime.version=1.8.0_191-b12, source=flush, > os.version=4.4.0-112-generic, > timestamp=1557241760483}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))} > [junit4] 2> 862065 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 > end_commit_flush > [junit4] 2> 862065 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] > o.a.s.u.p.LogUpdateProcessorFactory > [testkerberoscollection_shard1_replica_n1] webapp=/solr path=/update > params={_stateVer_=testkerberoscollection:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} > 0 50 > [junit4] 2> 862293 INFO (qtp722099044-4909) [n:127.0.0.1:36456_solr > c:testkerberoscollection s:shard1 r:core_node2 > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.S.Request > [testkerberoscollection_shard1_replica_n1] webapp=/solr path=/select > params={q=*:*&_stateVer_=testkerberoscollection:4&wt=javabin&version=2} > hits=1 status=0 QTime=0 > [junit4] 2> 862307 INFO (qtp722099044-4908) [n:127.0.0.1:36456_solr > ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params > name=testkerberoscollection&action=DELETE&wt=javabin&version=2 and > sendToOCPQueue=true > [junit4] 2> 862412 INFO > (OverseerThreadFactory-1875-thread-2-processing-n:127.0.0.1:36456_solr) > [n:127.0.0.1:36456_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler > Executing Collection > Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, > asyncId=null > [junit4] 1> Debug is true storeKey true useTicketCache false useKeyTab > true doNotPrompt false ticketCache is null isInitiator true KeyTab is > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001/tempDir-001/minikdc/keytabs > refreshKrb5Config is true principal is solr tryFirstPass is false > useFirstPass is false storePass is false clearPass is false > [junit4] 1> Refreshing Kerberos configuration > [junit4] 2> 862430 INFO (pool-20-thread-2) [ ] > o.a.k.k.k.s.r.KdcRequest The preauth data is empty. > [junit4] 2> 862431 INFO (pool-20-thread-2) [ ] > o.a.k.k.k.s.KdcHandler KRB error occurred while processing request:Additional > pre-authentication required > [junit4] 2> 862528 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.r.AsRequest AS_REQ ISSUE: authtime 1557241760957,[email protected] > for krbtgt/[email protected] > [junit4] 1> principal is [email protected] > [junit4] 1> Will use keytab > [junit4] 1> Commit Succeeded > [junit4] 1> > [junit4] 2> 862622 INFO (pool-20-thread-1) [ ] > o.a.k.k.k.s.r.TgsRequest TGS_REQ ISSUE: authtime 1557241761051,solr for > HTTP/[email protected] > [junit4] 2> 862724 INFO (qtp722099044-4905) [n:127.0.0.1:36456_solr > x:testkerberoscollection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing > metric reporters for > registry=solr.core.testkerberoscollection.shard1.replica_n1, tag=null > [junit4] 2> 862740 INFO (qtp722099044-4905) [n:127.0.0.1:36456_solr > x:testkerberoscollection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing > reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@19afa9f2: > rootName = solr_36456, domain = > solr.core.testkerberoscollection.shard1.replica_n1, service url = null, agent > id = null] for registry solr.core.testkerberoscollection.shard1.replica_n1 / > com.codahale.metrics.MetricRegistry@1d910d7 > [junit4] 2> 862852 INFO (qtp722099044-4905) [n:127.0.0.1:36456_solr > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.SolrCore > [testkerberoscollection_shard1_replica_n1] CLOSING SolrCore > org.apache.solr.core.SolrCore@3b20424a > [junit4] 2> 862852 INFO (qtp722099044-4905) [n:127.0.0.1:36456_solr > x:testkerberoscollection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing > metric reporters for > registry=solr.core.testkerberoscollection.shard1.replica_n1, tag=3b20424a > [junit4] 2> 862852 INFO (qtp722099044-4905) [n:127.0.0.1:36456_solr > x:testkerberoscollection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing > metric reporters for > registry=solr.collection.testkerberoscollection.shard1.leader, tag=3b20424a > [junit4] 2> 862908 INFO (qtp722099044-4905) [n:127.0.0.1:36456_solr > x:testkerberoscollection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful > update of terms at /collections/testkerberoscollection/terms/shard1 to > Terms{values={}, version=2} > [junit4] 2> 862940 INFO (qtp722099044-4905) [n:127.0.0.1:36456_solr > x:testkerberoscollection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] > webapp=null path=/admin/cores > params={deleteInstanceDir=true&deleteMetricsHistory=true&core=testkerberoscollection_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} > status=0 QTime=217 > [junit4] 2> 863012 INFO (zkCallback-1491-thread-3) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testkerberoscollection/state.json] for collection > [testkerberoscollection] has occurred - updating... (live nodes size: [1]) > [junit4] 2> 863012 INFO (zkCallback-1491-thread-1) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testkerberoscollection/state.json] for collection > [testkerberoscollection] has occurred - updating... (live nodes size: [1]) > [junit4] 2> 863015 INFO (zkCallback-1491-thread-2) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testkerberoscollection/state.json] for collection > [testkerberoscollection] has occurred - updating... (live nodes size: [1]) > [junit4] 2> 863138 INFO (zkCallback-1491-thread-2) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDeleted > path:/collections/testkerberoscollection/state.json] for collection > [testkerberoscollection] has occurred - updating... (live nodes size: [1]) > [junit4] 2> 863138 INFO (zkCallback-1491-thread-3) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDeleted > path:/collections/testkerberoscollection/state.json] for collection > [testkerberoscollection] has occurred - updating... (live nodes size: [1]) > [junit4] 2> 863138 INFO (zkCallback-1491-thread-1) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDeleted > path:/collections/testkerberoscollection/state.json] for collection > [testkerberoscollection] has occurred - updating... (live nodes size: [1]) > [junit4] 2> 863182 INFO (qtp722099044-4908) [n:127.0.0.1:36456_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections > params={name=testkerberoscollection&action=DELETE&wt=javabin&version=2} > status=0 QTime=874 > [junit4] 2> 863198 INFO > (TEST-TestSolrCloudWithHadoopAuthPlugin.testBasics-seed#[D251F4D3C6FA0825]) [ > ] o.a.s.c.AbstractDistribZkTestBase Wait for collection to disappear - > collection: testkerberoscollection failOnTimeout:true timeout (sec):330 > [junit4] 2> 863206 INFO > (TEST-TestSolrCloudWithHadoopAuthPlugin.testBasics-seed#[D251F4D3C6FA0825]) [ > ] o.a.s.c.AbstractDistribZkTestBase Collection has disappeared - > collection: testkerberoscollection > [junit4] 2> 863206 INFO > (TEST-TestSolrCloudWithHadoopAuthPlugin.testBasics-seed#[D251F4D3C6FA0825]) [ > ] o.a.s.c.SolrCloudAuthTestCase First metrics count assert failed, pausing > 2s before re-attempt > [junit4] 2> 864413 INFO > (OverseerCollectionConfigSetProcessor-74651453398122501-127.0.0.1:36456_solr-n_0000000000) > [n:127.0.0.1:36456_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: > /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may > have disconnected from ZooKeeper > [junit4] 2> 865220 INFO > (TEST-TestSolrCloudWithHadoopAuthPlugin.testBasics-seed#[D251F4D3C6FA0825]) [ > ] o.a.s.SolrTestCaseJ4 ###Ending testBasics > [junit4] 2> NOTE: reproduce with: ant test > -Dtestcase=TestSolrCloudWithHadoopAuthPlugin -Dtests.method=testBasics > -Dtests.seed=D251F4D3C6FA0825 -Dtests.multiplier=2 -Dtests.slow=true > -Dtests.locale=ru-RU -Dtests.timezone=Asia/Qyzylorda -Dtests.asserts=true > -Dtests.file.encoding=UTF-8 > [junit4] FAILURE 8.79s J1 | TestSolrCloudWithHadoopAuthPlugin.testBasics > <<< > [junit4] > Throwable #1: java.lang.AssertionError: Expected metric > minimums for prefix SECURITY./authentication.: {failMissingCredentials=0, > authenticated=8, passThrough=0, failWrongCredentials=8, requests=16, > errors=0}, but got: {failMissingCredentials=0, authenticated=8, > passThrough=0, totalTime=579767495, failWrongCredentials=6, > requestTimes=1521, requests=14, errors=0} > [junit4] > at > __randomizedtesting.SeedInfo.seed([D251F4D3C6FA0825:EF895AFFFE145655]:0) > [junit4] > at > org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums(SolrCloudAuthTestCase.java:129) > [junit4] > at > org.apache.solr.cloud.SolrCloudAuthTestCase.assertAuthMetricsMinimums(SolrCloudAuthTestCase.java:83) > [junit4] > at > org.apache.solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin.testCollectionCreateSearchDelete(TestSolrCloudWithHadoopAuthPlugin.java:139) > [junit4] > at > org.apache.solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin.testBasics(TestSolrCloudWithHadoopAuthPlugin.java:108) > [junit4] > at java.lang.Thread.run(Thread.java:748) > [junit4] 2> 865223 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.k.k.k.s.i.DefaultInternalKdcServerImpl Default Internal kdc server > stopped. > [junit4] 2> 866257 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.c.CoreContainer Shutting down CoreContainer instance=721276841 > [junit4] 2> 866257 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.c.ZkController Remove node as live in > ZooKeeper:/live_nodes/127.0.0.1:36456_solr > [junit4] 2> 866272 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.c.ZkController Publish this node as DOWN... > [junit4] 2> 866273 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.c.ZkController Publish node=127.0.0.1:36456_solr as DOWN > [junit4] 2> 866402 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, > tag=null > [junit4] 2> 866402 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@14ced5e5: rootName = > solr_36456, domain = solr.node, service url = null, agent id = null] for > registry solr.node / com.codahale.metrics.MetricRegistry@5bd478de > [junit4] 2> 866405 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, > tag=null > [junit4] 2> 866405 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@1185f71c: rootName = > solr_36456, domain = solr.jvm, service url = null, agent id = null] for > registry solr.jvm / com.codahale.metrics.MetricRegistry@375c45ab > [junit4] 2> 866440 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, > tag=null > [junit4] 2> 866440 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@69436321: rootName = > solr_36456, domain = solr.jetty, service url = null, agent id = null] for > registry solr.jetty / com.codahale.metrics.MetricRegistry@7158613a > [junit4] 2> 866440 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, > tag=null > [junit4] 2> 866459 INFO (closeThreadPool-1511-thread-2) [ ] > o.a.s.c.Overseer Overseer > (id=74651453398122501-127.0.0.1:36456_solr-n_0000000000) closing > [junit4] 2> 866511 INFO > (OverseerStateUpdate-74651453398122501-127.0.0.1:36456_solr-n_0000000000) > [n:127.0.0.1:36456_solr ] o.a.s.c.Overseer Overseer Loop exiting : > 127.0.0.1:36456_solr > [junit4] 2> 866527 INFO > (OverseerAutoScalingTriggerThread-74651453398122501-127.0.0.1:36456_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we > are closed, exiting. > [junit4] 2> 866533 INFO (closeThreadPool-1511-thread-1) [ ] > o.a.s.c.Overseer Overseer > (id=74651453398122501-127.0.0.1:36456_solr-n_0000000000) closing > [junit4] 2> 866537 INFO (jetty-closer-1507-thread-1) [ ] > o.a.s.c.Overseer Overseer > (id=74651453398122501-127.0.0.1:36456_solr-n_0000000000) closing > [junit4] 2> 866545 INFO (jetty-closer-1507-thread-1) [ ] > o.e.j.s.AbstractConnector Stopped > ServerConnector@21797c98{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0} > [junit4] 2> 866545 INFO (jetty-closer-1507-thread-1) [ ] > o.e.j.s.h.ContextHandler Stopped > o.e.j.s.ServletContextHandler@2373f6d2{/solr,null,UNAVAILABLE} > [junit4] 2> 866545 INFO (jetty-closer-1507-thread-1) [ ] > o.e.j.s.session node0 Stopped scavenging > [junit4] 2> 866546 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.c.ZkTestServer Shutting down ZkTestServer. > [junit4] 2> 866547 WARN (ZkTestServer Run Thread) [ ] > o.a.s.c.ZkTestServer Watch limit violations: > [junit4] 2> Maximum concurrent create/delete watches above limit: > [junit4] 2> > [junit4] 2> 4 > /solr/collections/testkerberoscollection/terms/shard1 > [junit4] 2> 3 /solr/aliases.json > [junit4] 2> 3 > /solr/collections/testkerberoscollection/state.json > [junit4] 2> 3 /solr/clusterprops.json > [junit4] 2> > [junit4] 2> Maximum concurrent data watches above limit: > [junit4] 2> > [junit4] 2> 8 > /solr/collections/testkerberoscollection/state.json > [junit4] 2> 3 /solr/clusterstate.json > [junit4] 2> 3 /solr/clusterprops.json > [junit4] 2> > [junit4] 2> Maximum concurrent children watches above limit: > [junit4] 2> > [junit4] 2> 3 /solr/live_nodes > [junit4] 2> 3 /solr/collections > [junit4] 2> > [junit4] 2> 866549 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39325 > [junit4] 2> 866549 INFO > (SUITE-TestSolrCloudWithHadoopAuthPlugin-seed#[D251F4D3C6FA0825]-worker) [ > ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39325 > [junit4] 2> NOTE: leaving temporary files on disk at: > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J1/temp/solr.security.hadoop.TestSolrCloudWithHadoopAuthPlugin_D251F4D3C6FA0825-001 > [junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): > {_version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), > > _root_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), > id=PostingsFormat(name=Asserting)}, docValues:{}, maxPointsInLeafNode=877, > maxMBSortInHeap=6.778557654438232, > sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@11ff40e4), > locale=ru-RU, timezone=Asia/Qyzylorda > [junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation > 1.8.0_191 (64-bit)/cpus=4,threads=1,free=206373112,total=476053504 > [junit4] 2> NOTE: All tests run in this JVM: > [ChaosMonkeyNothingIsSafeWithPullReplicasTest, StatsComponentTest, > MetricsConfigTest, TestHdfsBackupRestoreCore, TestComplexPhraseQParserPlugin, > AssignTest, TestSimLargeCluster, CdcrWithNodesRestartsTest, > SpatialRPTFieldTypeTest, TestSimPolicyCloud, TestReplicaProperties, > SplitShardTest, TestCoreContainer, TestHighlightDedupGrouping, > ResponseHeaderTest, TestPushWriter, TestRemoteStreaming, JavabinLoaderTest, > ExitableDirectoryReaderTest, AnalysisErrorHandlingTest, > TestIndexingPerformance, TestCollectionAPIs, LargeFieldTest, > TestSearcherReuse, DistributedFacetPivotWhiteBoxTest, > TestReplicationHandlerDiskOverFlow, TestNestedDocsSort, > MoreLikeThisHandlerTest, ZkNodePropsTest, TestLeaderElectionWithEmptyReplica, > LeaderElectionIntegrationTest, TestConfigSetImmutable, > ReplicaListTransformerTest, ManagedSchemaRoundRobinCloudTest, > TestGraphMLResponseWriter, AtomicUpdateRemovalJavabinTest, > TestDistributedStatsComponentCardinality, HdfsRestartWhileUpdatingTest, > InfixSuggestersTest, TestCSVResponseWriter, TestNumericTokenStream, > FullHLLTest, TestSolrDeletionPolicy2, TestCSVLoader, > TestEmbeddedSolrServerConstructors, TestSolrCloudWithHadoopAuthPlugin] > [junit4] Completed [105/855 (1!)] on J1 in 15.31s, 1 test, 1 failure <<< > FAILURES! > > [...truncated 817 lines...] > [junit4] Suite: org.apache.solr.security.JWTAuthPluginIntegrationTest > [junit4] 2> 2113737 INFO > (SUITE-JWTAuthPluginIntegrationTest-seed#[D251F4D3C6FA0825]-worker) [ ] > o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: > test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom > [junit4] 2> Creating dataDir: > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J2/temp/solr.security.JWTAuthPluginIntegrationTest_D251F4D3C6FA0825-001/init-core-data-001 > [junit4] 2> 2113737 WARN > (SUITE-JWTAuthPluginIntegrationTest-seed#[D251F4D3C6FA0825]-worker) [ ] > o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=42 numCloses=42 > [junit4] 2> 2113738 INFO > (SUITE-JWTAuthPluginIntegrationTest-seed#[D251F4D3C6FA0825]-worker) [ ] > o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) > w/NUMERIC_DOCVALUES_SYSPROP=true > [junit4] 2> 2113739 INFO > (SUITE-JWTAuthPluginIntegrationTest-seed#[D251F4D3C6FA0825]-worker) [ ] > o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: > @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) > [junit4] 2> 2113741 INFO > (TEST-JWTAuthPluginIntegrationTest.infoRequestWithoutToken-seed#[D251F4D3C6FA0825]) > [ ] o.a.s.SolrTestCaseJ4 ###Starting infoRequestWithoutToken > [junit4] 2> 2113742 INFO > (TEST-JWTAuthPluginIntegrationTest.infoRequestWithoutToken-seed#[D251F4D3C6FA0825]) > [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J2/temp/solr.security.JWTAuthPluginIntegrationTest_D251F4D3C6FA0825-001/tempDir-001 > [junit4] 2> 2113742 INFO > (TEST-JWTAuthPluginIntegrationTest.infoRequestWithoutToken-seed#[D251F4D3C6FA0825]) > [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER > [junit4] 2> 2113754 INFO (ZkTestServer Run Thread) [ ] > o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 > [junit4] 2> 2113754 INFO (ZkTestServer Run Thread) [ ] > o.a.s.c.ZkTestServer Starting server > [junit4] 2> 2113854 INFO > (TEST-JWTAuthPluginIntegrationTest.infoRequestWithoutToken-seed#[D251F4D3C6FA0825]) > [ ] o.a.s.c.ZkTestServer start zk server on port:44837 > [junit4] 2> 2113854 INFO > (TEST-JWTAuthPluginIntegrationTest.infoRequestWithoutToken-seed#[D251F4D3C6FA0825]) > [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:44837 > [junit4] 2> 2113854 INFO > (TEST-JWTAuthPluginIntegrationTest.infoRequestWithoutToken-seed#[D251F4D3C6FA0825]) > [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 44837 > [junit4] 2> 2113906 INFO (zkConnectionManagerCallback-8971-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2113950 INFO (zkConnectionManagerCallback-8973-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2113967 INFO (zkConnectionManagerCallback-8975-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2113971 WARN (jetty-launcher-8976-thread-1) [ ] > o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time > [junit4] 2> 2113971 INFO (jetty-launcher-8976-thread-1) [ ] > o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) > [junit4] 2> 2113971 INFO (jetty-launcher-8976-thread-1) [ ] > o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... > [junit4] 2> 2113971 INFO (jetty-launcher-8976-thread-1) [ ] > o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: > c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 > [junit4] 2> 2113972 WARN (jetty-launcher-8976-thread-2) [ ] > o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time > [junit4] 2> 2113972 INFO (jetty-launcher-8976-thread-2) [ ] > o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) > [junit4] 2> 2113972 INFO (jetty-launcher-8976-thread-2) [ ] > o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... > [junit4] 2> 2113972 INFO (jetty-launcher-8976-thread-2) [ ] > o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: > c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 > [junit4] 2> 2113972 INFO (jetty-launcher-8976-thread-1) [ ] > o.e.j.s.session DefaultSessionIdManager workerName=node0 > [junit4] 2> 2113972 INFO (jetty-launcher-8976-thread-1) [ ] > o.e.j.s.session No SessionScavenger set, using defaults > [junit4] 2> 2113972 INFO (jetty-launcher-8976-thread-1) [ ] > o.e.j.s.session node0 Scavenging every 600000ms > [junit4] 2> 2113973 INFO (jetty-launcher-8976-thread-1) [ ] > o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@5209da3b{/solr,null,AVAILABLE} > [junit4] 2> 2113978 INFO (jetty-launcher-8976-thread-1) [ ] > o.e.j.s.AbstractConnector Started > ServerConnector@2a17b75a{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:44538} > [junit4] 2> 2113978 INFO (jetty-launcher-8976-thread-1) [ ] > o.e.j.s.Server Started @2114306ms > [junit4] 2> 2113978 INFO (jetty-launcher-8976-thread-1) [ ] > o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, > hostPort=44538} > [junit4] 2> 2113978 ERROR (jetty-launcher-8976-thread-1) [ ] > o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be > missing or incomplete. > [junit4] 2> 2113978 INFO (jetty-launcher-8976-thread-1) [ ] > o.a.s.s.SolrDispatchFilter Using logger factory > org.apache.logging.slf4j.Log4jLoggerFactory > [junit4] 2> 2113978 INFO (jetty-launcher-8976-thread-1) [ ] > o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version > 8.1.0 > [junit4] 2> 2113978 INFO (jetty-launcher-8976-thread-1) [ ] > o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port > null > [junit4] 2> 2113978 INFO (jetty-launcher-8976-thread-1) [ ] > o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null > [junit4] 2> 2113978 INFO (jetty-launcher-8976-thread-1) [ ] > o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: > 2019-05-07T15:30:12.395Z > [junit4] 2> 2113979 INFO (jetty-launcher-8976-thread-2) [ ] > o.e.j.s.session DefaultSessionIdManager workerName=node0 > [junit4] 2> 2113979 INFO (jetty-launcher-8976-thread-2) [ ] > o.e.j.s.session No SessionScavenger set, using defaults > [junit4] 2> 2113979 INFO (jetty-launcher-8976-thread-2) [ ] > o.e.j.s.session node0 Scavenging every 660000ms > [junit4] 2> 2113979 INFO (jetty-launcher-8976-thread-2) [ ] > o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@1521d4c5{/solr,null,AVAILABLE} > [junit4] 2> 2113980 INFO (zkConnectionManagerCallback-8978-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2113980 INFO (jetty-launcher-8976-thread-2) [ ] > o.e.j.s.AbstractConnector Started > ServerConnector@58ab8dd5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:41707} > [junit4] 2> 2113980 INFO (jetty-launcher-8976-thread-2) [ ] > o.e.j.s.Server Started @2114308ms > [junit4] 2> 2113980 INFO (jetty-launcher-8976-thread-1) [ ] > o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... > [junit4] 2> 2113980 INFO (jetty-launcher-8976-thread-2) [ ] > o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, > hostPort=41707} > [junit4] 2> 2113980 ERROR (jetty-launcher-8976-thread-2) [ ] > o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be > missing or incomplete. > [junit4] 2> 2113980 INFO (jetty-launcher-8976-thread-2) [ ] > o.a.s.s.SolrDispatchFilter Using logger factory > org.apache.logging.slf4j.Log4jLoggerFactory > [junit4] 2> 2113980 INFO (jetty-launcher-8976-thread-2) [ ] > o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version > 8.1.0 > [junit4] 2> 2113980 INFO (jetty-launcher-8976-thread-2) [ ] > o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port > null > [junit4] 2> 2113980 INFO (jetty-launcher-8976-thread-2) [ ] > o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null > [junit4] 2> 2113980 INFO (jetty-launcher-8976-thread-2) [ ] > o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: > 2019-05-07T15:30:12.397Z > [junit4] 2> 2113983 INFO (zkConnectionManagerCallback-8980-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2113983 INFO (jetty-launcher-8976-thread-2) [ ] > o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... > [junit4] 2> 2114066 INFO (jetty-launcher-8976-thread-2) [ ] > o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: > WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] > [junit4] 2> 2114067 WARN (jetty-launcher-8976-thread-2) [ ] > o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for > SslContextFactory@518ea535[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 2114070 WARN (jetty-launcher-8976-thread-2) [ ] > o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for > SslContextFactory@7ceb2edc[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 2114071 INFO (jetty-launcher-8976-thread-2) [ ] > o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44837/solr > [junit4] 2> 2114072 INFO (zkConnectionManagerCallback-8988-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2114074 INFO (zkConnectionManagerCallback-8990-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2114127 INFO (jetty-launcher-8976-thread-2) > [n:127.0.0.1:41707_solr ] o.a.s.c.OverseerElectionContext I am going to be > the leader 127.0.0.1:41707_solr > [junit4] 2> 2114127 INFO (jetty-launcher-8976-thread-2) > [n:127.0.0.1:41707_solr ] o.a.s.c.Overseer Overseer > (id=74651535992160262-127.0.0.1:41707_solr-n_0000000000) starting > [junit4] 2> 2114165 INFO (zkConnectionManagerCallback-8997-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2114167 INFO (jetty-launcher-8976-thread-2) > [n:127.0.0.1:41707_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster > at 127.0.0.1:44837/solr ready > [junit4] 2> 2114167 INFO > (OverseerStateUpdate-74651535992160262-127.0.0.1:41707_solr-n_0000000000) > [n:127.0.0.1:41707_solr ] o.a.s.c.Overseer Starting to work on the main > queue : 127.0.0.1:41707_solr > [junit4] 2> 2114183 INFO (jetty-launcher-8976-thread-1) [ ] > o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: > WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] > [junit4] 2> 2114197 INFO (jetty-launcher-8976-thread-2) > [n:127.0.0.1:41707_solr ] o.a.s.c.ZkController Register node as live in > ZooKeeper:/live_nodes/127.0.0.1:41707_solr > [junit4] 2> 2114203 WARN (jetty-launcher-8976-thread-1) [ ] > o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for > SslContextFactory@399b3730[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 2114205 INFO (jetty-launcher-8976-thread-2) > [n:127.0.0.1:41707_solr ] o.a.s.c.CoreContainer Initializing > authentication plugin: solr.JWTAuthPlugin > [junit4] 2> 2114210 INFO (zkCallback-8996-thread-1) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) > [junit4] 2> 2114215 INFO (zkCallback-8989-thread-1) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) > [junit4] 2> 2114224 WARN (jetty-launcher-8976-thread-1) [ ] > o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for > SslContextFactory@7e36f9c3[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 2114225 INFO (jetty-launcher-8976-thread-1) [ ] > o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44837/solr > [junit4] 2> 2114270 WARN (jetty-launcher-8976-thread-2) > [n:127.0.0.1:41707_solr ] o.a.s.s.JWTAuthPlugin Warning: No adminUiScope > provided, fallback to 'solr' as required scope. If this is not correct, the > Admin UI login may not work > [junit4] 2> 2114290 INFO (zkConnectionManagerCallback-9003-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2114310 INFO (zkConnectionManagerCallback-9005-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2114313 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from > ZooKeeper... (0) -> (1) > [junit4] 2> 2114317 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.c.ZkController Publish > node=127.0.0.1:44538_solr as DOWN > [junit4] 2> 2114317 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating > transient cache for 2147483647 transient cores > [junit4] 2> 2114317 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.c.ZkController Register node as live in > ZooKeeper:/live_nodes/127.0.0.1:44538_solr > [junit4] 2> 2114318 INFO (zkCallback-8996-thread-1) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) > [junit4] 2> 2114319 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.c.CoreContainer Initializing > authentication plugin: solr.JWTAuthPlugin > [junit4] 2> 2114319 WARN (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.s.JWTAuthPlugin Warning: No adminUiScope > provided, fallback to 'solr' as required scope. If this is not correct, the > Admin UI login may not work > [junit4] 2> 2114341 INFO (zkCallback-9004-thread-1) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) > [junit4] 2> 2114366 INFO (zkCallback-8989-thread-2) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) > [junit4] 2> 2114377 INFO (zkConnectionManagerCallback-9013-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2114379 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from > ZooKeeper... (0) -> (2) > [junit4] 2> 2114380 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster > at 127.0.0.1:44837/solr ready > [junit4] 2> 2114386 INFO (jetty-launcher-8976-thread-2) > [n:127.0.0.1:41707_solr ] o.a.s.h.a.MetricsHistoryHandler No .system > collection, keeping metrics history in memory. > [junit4] 2> 2114406 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.h.a.MetricsHistoryHandler No .system > collection, keeping metrics history in memory. > [junit4] 2> 2114421 INFO (jetty-launcher-8976-thread-2) > [n:127.0.0.1:41707_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_41707.solr.node' (registry 'solr.node') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@b7a6ec4 > [junit4] 2> 2114431 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_44538.solr.node' (registry 'solr.node') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@b7a6ec4 > [junit4] 2> 2114481 INFO (jetty-launcher-8976-thread-2) > [n:127.0.0.1:41707_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_41707.solr.jvm' (registry 'solr.jvm') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@b7a6ec4 > [junit4] 2> 2114482 INFO (jetty-launcher-8976-thread-2) > [n:127.0.0.1:41707_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_41707.solr.jetty' (registry 'solr.jetty') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@b7a6ec4 > [junit4] 2> 2114483 INFO (jetty-launcher-8976-thread-2) > [n:127.0.0.1:41707_solr ] o.a.s.c.CorePropertiesLocator Found 0 core > definitions underneath > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J2/temp/solr.security.JWTAuthPluginIntegrationTest_D251F4D3C6FA0825-001/tempDir-001/node2/. > [junit4] 2> 2114483 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_44538.solr.jvm' (registry 'solr.jvm') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@b7a6ec4 > [junit4] 2> 2114483 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_44538.solr.jetty' (registry 'solr.jetty') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@b7a6ec4 > [junit4] 2> 2114484 INFO (jetty-launcher-8976-thread-1) > [n:127.0.0.1:44538_solr ] o.a.s.c.CorePropertiesLocator Found 0 core > definitions underneath > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/build/solr-core/test/J2/temp/solr.security.JWTAuthPluginIntegrationTest_D251F4D3C6FA0825-001/tempDir-001/node1/. > [junit4] 2> 2114633 INFO (zkConnectionManagerCallback-9019-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 2114634 INFO > (TEST-JWTAuthPluginIntegrationTest.infoRequestWithoutToken-seed#[D251F4D3C6FA0825]) > [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> > (2) > [junit4] 2> 2114635 INFO > (TEST-JWTAuthPluginIntegrationTest.infoRequestWithoutToken-seed#[D251F4D3C6FA0825]) > [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at > 127.0.0.1:44837/solr ready > [junit4] 2> 2114951 INFO (qtp1313089676-22886) [ ] > o.a.s.s.JWTAuthPlugin JWT Authentication attempt failed: Require > authentication > [junit4] 2> 2114974 INFO (jetty-closer-9020-thread-1) [ ] > o.a.s.c.CoreContainer Shutting down CoreContainer instance=1499472627 > [junit4] 2> 2114974 INFO (jetty-closer-9020-thread-1) [ ] > o.a.s.c.ZkController Remove node as live in > ZooKeeper:/live_nodes/127.0.0.1:44538_solr > [junit4] 2> 2114974 INFO (jetty-closer-9020-thread-1) [ ] > o.a.s.c.ZkController Publish this node as DOWN... > [junit4] 2> 2114974 INFO (jetty-closer-9020-thread-1) [ ] > o.a.s.c.ZkController Publish node=127.0.0.1:44538_solr as DOWN > [junit4] 2> 2114980 INFO (jetty-closer-9020-thread-2) [ ] > o.a.s.c.CoreContainer Shutting down CoreContainer instance=873691572 > [junit4] 2> 2114980 INFO (jetty-closer-9020-thread-2) [ ] > o.a.s.c.ZkController Remove node as live in > ZooKeeper:/live_nodes/127.0.0.1:41707_solr > [junit4] 2> 2114982 INFO (jetty-closer-9020-thread-2) [ ] > o.a.s.c.ZkController Publish this node as DOWN... > [junit4] 2> 2114982 INFO (jetty-closer-9020-thread-2) [ ] > o.a.s.c.ZkController Publish node=127.0.0.1:41707_solr as DOWN > [junit4] 2> 2115022 INFO (jetty-closer-9020-thread-1) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, > tag=null > [junit4] 2> 2115022 INFO (jetty-closer-9020-thread-1) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@cd5c633: rootName = > solr_44538, domain = solr.node, service url = null, agent id = null] for > registry solr.node / com.codahale.metrics.MetricRegistry@20925144 > [junit4] 2> 2115025 INFO (jetty-closer-9020-thread-1) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, > tag=null > [junit4] 2> 2115025 INFO (jetty-closer-9020-thread-1) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@2a1ebc2c: rootName = > solr_44538, domain = solr.jvm, service url = null, agent id = null] for > registry solr.jvm / com.codahale.metrics.MetricRegistry@97060e0 > [junit4] 2> 2115027 INFO (jetty-closer-9020-thread-1) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, > tag=null > [junit4] 2> 2115027 INFO (jetty-closer-9020-thread-1) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@173cfa92: rootName = > solr_44538, domain = solr.jetty, service url = null, agent id = null] for > registry solr.jetty / com.codahale.metrics.MetricRegistry@4b760b3f > [junit4] 2> 2115027 INFO (jetty-closer-9020-thread-1) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, > tag=null > [junit4] 2> 2115030 INFO (jetty-closer-9020-thread-2) [ ] > o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for > 2147483647 transient cores > [junit4] 2> 2115046 INFO (jetty-closer-9020-thread-2) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, > tag=null > [junit4] 2> 2115046 INFO (jetty-closer-9020-thread-2) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@415b98d2: rootName = > solr_41707, domain = solr.node, service url = null, agent id = null] for > registry solr.node / com.codahale.metrics.MetricRegistry@3611a520 > [junit4] 2> 2115050 INFO (zkCallback-8996-thread-1) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) > [junit4] 2> 2115051 INFO (jetty-closer-9020-thread-2) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, > tag=null > [junit4] 2> 2115051 INFO (jetty-closer-9020-thread-2) [ ] > o.a.s.m.r.SolrJmxReporter Closing reporter > [org.apache.solr.metrics.reporters.SolrJmxReporter@67a2f75: rootName = > solr_41707, domain = solr.jvm, service url = null, agent id = null] for > registry solr.jvm / com.codahale.metrics.MetricRegistry@97060e0 > [junit4] 2> 2115051 INFO (zkCallback-8989-thread-1) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) > [junit4] 2> 2115053 INFO (jetty-closer-9020-thread-2) [ ] > o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, > tag=null > [junit4] 2> 2115053 INFO (jetty-closer-9020-threa > > [...truncated too long message...] > > s :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > ivy-availability-check: > [loadresource] Do not set property disallowed.ivy.jars.list as its length is > 0. > > -ivy-fail-disallowed-ivy-version: > > ivy-fail: > > ivy-fail: > > ivy-configure: > [ivy:configure] :: loading settings :: file = > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/lucene/top-level-ivy-settings.xml > > resolve: > > jar-checksums: > [mkdir] Created dir: > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/null2046754742 > [copy] Copying 239 files to > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/null2046754742 > [delete] Deleting directory > /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.1/solr/null2046754742 > > check-working-copy: > [ivy:cachepath] :: resolving dependencies :: > #;[email protected] > [ivy:cachepath] confs: [default] > [ivy:cachepath] found > org.eclipse.jgit#org.eclipse.jgit;5.3.0.201903130848-r in public > [ivy:cachepath] found com.jcraft#jsch;0.1.54 in public > [ivy:cachepath] found com.jcraft#jzlib;1.1.1 in public > [ivy:cachepath] found com.googlecode.javaewah#JavaEWAH;1.1.6 in public > [ivy:cachepath] found org.slf4j#slf4j-api;1.7.2 in public > [ivy:cachepath] found org.bouncycastle#bcpg-jdk15on;1.60 in public > [ivy:cachepath] found org.bouncycastle#bcprov-jdk15on;1.60 in public > [ivy:cachepath] found org.bouncycastle#bcpkix-jdk15on;1.60 in public > [ivy:cachepath] found org.slf4j#slf4j-nop;1.7.2 in public > [ivy:cachepath] :: resolution report :: resolve 64ms :: artifacts dl 20ms > --------------------------------------------------------------------- > | | modules || artifacts | > | conf | number| search|dwnlded|evicted|| number|dwnlded| > --------------------------------------------------------------------- > | default | 9 | 0 | 0 | 0 || 9 | 0 | > --------------------------------------------------------------------- > [wc-checker] Initializing working copy... > [wc-checker] Checking working copy status... > > -jenkins-base: > > BUILD SUCCESSFUL > Total time: 221 minutes 45 seconds > Archiving artifacts > java.lang.InterruptedException: no matches found within 10000 > at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847) > at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726) > at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707) > at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086) > Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2 > at > hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741) > at > hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357) > at hudson.remoting.Channel.call(Channel.java:955) > at hudson.FilePath.act(FilePath.java:1072) > at hudson.FilePath.act(FilePath.java:1061) > at hudson.FilePath.validateAntFileMask(FilePath.java:2705) > at > hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) > at > hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) > at > hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) > at > hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) > at > hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) > at hudson.model.Build$BuildExecution.post2(Build.java:186) > at > hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) > at hudson.model.Run.execute(Run.java:1835) > at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) > at > hudson.model.ResourceController.execute(ResourceController.java:97) > at hudson.model.Executor.run(Executor.java:429) > Caused: hudson.FilePath$TunneledInterruptedException > at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3088) > at hudson.remoting.UserRequest.perform(UserRequest.java:212) > at hudson.remoting.UserRequest.perform(UserRequest.java:54) > at hudson.remoting.Request$2.run(Request.java:369) > at > hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72) > at java.util.concurrent.FutureTask.run(FutureTask.java:266) > at > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) > at > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) > at java.lang.Thread.run(Thread.java:748) > Caused: java.lang.InterruptedException: java.lang.InterruptedException: no > matches found within 10000 > at hudson.FilePath.act(FilePath.java:1074) > at hudson.FilePath.act(FilePath.java:1061) > at hudson.FilePath.validateAntFileMask(FilePath.java:2705) > at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243) > at > hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81) > at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) > at > hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744) > at > hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690) > at hudson.model.Build$BuildExecution.post2(Build.java:186) > at > hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635) > at hudson.model.Run.execute(Run.java:1835) > at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43) > at hudson.model.ResourceController.execute(ResourceController.java:97) > at hudson.model.Executor.run(Executor.java:429) > No artifacts found that match the file pattern > "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error? > Recording test results > Build step 'Publish JUnit test result report' changed build result to UNSTABLE > Email was triggered for: Unstable (Test Failures) > Sending email for trigger: Unstable (Test Failures) > > --------------------------------------------------------------------- > To unsubscribe, e-mail: [email protected] > For additional commands, e-mail: [email protected] --------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
