Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20538/ Java: 64bit/jdk-9 -XX:+UseCompressedOops -XX:+UseSerialGC --illegal-access=deny
2 tests failed. FAILED: org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelExecutorStream Error Message: Error from server at https://127.0.0.1:45419/solr/mainCorpus_shard2_replica_n3: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 404 </title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing /solr/mainCorpus_shard2_replica_n3/update. Reason: <pre> Can not find: /solr/mainCorpus_shard2_replica_n3/update</pre></p> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.20.v20170531</a><hr/> </body> </html> Stack Trace: org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from server at https://127.0.0.1:45419/solr/mainCorpus_shard2_replica_n3: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 404 </title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing /solr/mainCorpus_shard2_replica_n3/update. Reason: <pre> Can not find: /solr/mainCorpus_shard2_replica_n3/update</pre></p> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.20.v20170531</a><hr/> </body> </html> at __randomizedtesting.SeedInfo.seed([3C51A5AE753A1D89:8146D0B74C1620D4]:0) at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:539) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:993) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:862) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:793) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178) at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233) at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelExecutorStream(StreamExpressionTest.java:7287) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:45419/solr/mainCorpus_shard2_replica_n3: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 404 </title> </head> <body> <h2>HTTP ERROR: 404</h2> <p>Problem accessing /solr/mainCorpus_shard2_replica_n3/update. Reason: <pre> Can not find: /solr/mainCorpus_shard2_replica_n3/update</pre></p> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.20.v20170531</a><hr/> </body> </html> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:591) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.lambda$directUpdate$0(CloudSolrClient.java:516) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) ... 1 more FAILED: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenRenew Error Message: expected:<200> but was:<403> Stack Trace: java.lang.AssertionError: expected:<200> but was:<403> at __randomizedtesting.SeedInfo.seed([96A69256BBD92FC4:A13D66488315F260]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.renewDelegationToken(TestDelegationWithHadoopAuth.java:118) at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.verifyDelegationTokenRenew(TestDelegationWithHadoopAuth.java:302) at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenRenew(TestDelegationWithHadoopAuth.java:319) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) 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:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 13747 lines...] [junit4] Suite: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth [junit4] 2> 2765175 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[96A69256BBD92FC4]-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/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_96A69256BBD92FC4-001/init-core-data-001 [junit4] 2> 2765176 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[96A69256BBD92FC4]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 2765176 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[96A69256BBD92FC4]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 2765177 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[96A69256BBD92FC4]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_96A69256BBD92FC4-001/tempDir-001 [junit4] 2> 2765177 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[96A69256BBD92FC4]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2765177 INFO (Thread-7754) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2765177 INFO (Thread-7754) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2765179 ERROR (Thread-7754) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 2765277 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[96A69256BBD92FC4]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:33487 [junit4] 2> 2765282 INFO (jetty-launcher-3320-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 2765282 INFO (jetty-launcher-3320-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7bbe445a{/solr,null,AVAILABLE} [junit4] 2> 2765282 INFO (jetty-launcher-3320-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 2765283 INFO (jetty-launcher-3320-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6b2fa6cd{/solr,null,AVAILABLE} [junit4] 2> 2765284 INFO (jetty-launcher-3320-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@34cffae9{SSL,[ssl, http/1.1]}{127.0.0.1:33533} [junit4] 2> 2765284 INFO (jetty-launcher-3320-thread-1) [ ] o.e.j.s.Server Started @2766650ms [junit4] 2> 2765284 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33533} [junit4] 2> 2765284 ERROR (jetty-launcher-3320-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2765284 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2765284 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2765284 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 2765284 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-24T20:40:52.595926Z [junit4] 2> 2765291 INFO (jetty-launcher-3320-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@298bb3e3{SSL,[ssl, http/1.1]}{127.0.0.1:46289} [junit4] 2> 2765294 INFO (jetty-launcher-3320-thread-2) [ ] o.e.j.s.Server Started @2766661ms [junit4] 2> 2765295 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46289} [junit4] 2> 2765295 ERROR (jetty-launcher-3320-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2765295 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2765295 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2765295 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 2765295 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-24T20:40:52.606566Z [junit4] 2> 2765295 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2765297 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2765301 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33487/solr [junit4] 2> 2765302 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33487/solr [junit4] 2> 2765366 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2765366 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2765366 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46289_solr [junit4] 2> 2765366 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33533_solr [junit4] 2> 2765367 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.c.Overseer Overseer (id=98715936521519110-127.0.0.1:46289_solr-n_0000000000) starting [junit4] 2> 2765367 INFO (zkCallback-3332-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2765367 INFO (zkCallback-3331-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2765390 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin [junit4] 2> 2765392 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46289_solr [junit4] 2> 2765393 INFO (zkCallback-3332-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2765395 INFO (zkCallback-3331-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2765396 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.s.HadoopAuthPlugin Params: {delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, type=simple, zk-dt-secret-manager.enable=true, signer.secret.provider=zookeeper} [junit4] 2> 2765425 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin [junit4] 2> 2765426 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.s.HadoopAuthPlugin Params: {delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, type=simple, zk-dt-secret-manager.enable=true, signer.secret.provider=zookeeper} [junit4] 2> 2765435 INFO (jetty-launcher-3320-thread-1) [ ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 2765435 INFO (jetty-launcher-3320-thread-2) [ ] o.a.c.f.i.CuratorFrameworkImpl Starting [junit4] 2> 2765450 INFO (jetty-launcher-3320-thread-1-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED [junit4] 2> 2765450 INFO (jetty-launcher-3320-thread-2-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED [junit4] 2> 2765506 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests [junit4] 2> 2765515 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests [junit4] 2> 2765536 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33533.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e340018 [junit4] 2> 2765545 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33533.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e340018 [junit4] 2> 2765545 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33533.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e340018 [junit4] 2> 2765546 INFO (jetty-launcher-3320-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_96A69256BBD92FC4-001/tempDir-001/node1/. [junit4] 2> 2765551 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46289.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e340018 [junit4] 2> 2765583 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46289.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e340018 [junit4] 2> 2765583 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46289.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6e340018 [junit4] 2> 2765585 INFO (jetty-launcher-3320-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_96A69256BBD92FC4-001/tempDir-001/node2/. [junit4] IGNOR/A 0.00s J2 | TestDelegationWithHadoopAuth.testDelegationTokenCancelFail [junit4] > Assumption #1: 'awaitsfix' test group is disabled (@AwaitsFix(bugUrl="https://issues.apache.org/jira/browse/HADOOP-14044")) [junit4] 2> 2765613 INFO (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths [junit4] 2> 2765649 INFO (TEST-TestDelegationWithHadoopAuth.testZNodePaths-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths [junit4] 2> 2765658 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel [junit4] 2> 2765689 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2765689 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33487/solr ready [junit4] 2> 2765702 ERROR (qtp820672542-24868) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2] [junit4] 2> 2765714 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765717 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2765718 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33487/solr ready [junit4] 2> 2765724 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765737 ERROR (qtp820672542-24871) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_3] [junit4] 2> 2765746 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2765746 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33487/solr ready [junit4] 2> 2765750 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765758 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2765759 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33487/solr ready [junit4] 2> 2765762 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765764 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel [junit4] 2> 2765782 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenSolrClient-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient [junit4] 2> 2765791 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenSolrClient-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765797 INFO (qtp1577674873-24859) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAV61nwxmigFe2auQZgQEFJME6i3hGh8j2mNL7V7wyRkTlU2VB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2765797 INFO (qtp1577674873-24859) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAV61nwxmigFe2auQZgQEFJME6i3hGh8j2mNL7V7wyRkTlU2VB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2765798 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenSolrClient-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient [junit4] 2> 2765808 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew [junit4] 2> 2765812 WARN (qtp1577674873-24860) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: foo is trying to renew a token with wrong password [junit4] 2> 2765813 WARN (qtp1577674873-24860) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues [junit4] 2> 2765813 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765813 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDelegationWithHadoopAuth -Dtests.method=testDelegationTokenRenew -Dtests.seed=96A69256BBD92FC4 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=vun-TZ -Dtests.timezone=Australia/Hobart -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 0.02s J2 | TestDelegationWithHadoopAuth.testDelegationTokenRenew <<< [junit4] > Throwable #1: java.lang.AssertionError: expected:<200> but was:<403> [junit4] > at __randomizedtesting.SeedInfo.seed([96A69256BBD92FC4:A13D66488315F260]:0) [junit4] > at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.renewDelegationToken(TestDelegationWithHadoopAuth.java:118) [junit4] > at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.verifyDelegationTokenRenew(TestDelegationWithHadoopAuth.java:302) [junit4] > at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenRenew(TestDelegationWithHadoopAuth.java:319) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564) [junit4] > at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 2765826 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail [junit4] 2> 2765829 WARN (qtp1577674873-24862) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: bar tries to renew a token with renewer foo [junit4] 2> 2765831 WARN (qtp1577674873-24862) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues [junit4] 2> 2765831 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765832 WARN (qtp820672542-24868) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: bar tries to renew a token with renewer foo [junit4] 2> 2765832 WARN (qtp820672542-24868) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues [junit4] 2> 2765835 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765839 WARN (qtp1577674873-24860) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew a token with renewer renewUser [junit4] 2> 2765839 WARN (qtp1577674873-24860) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues [junit4] 2> 2765843 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765844 WARN (qtp820672542-24873) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew a token with renewer renewUser [junit4] 2> 2765844 WARN (qtp820672542-24873) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues [junit4] 2> 2765845 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765845 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail [junit4] 2> 2765860 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify [junit4] 2> 2765865 INFO (qtp1577674873-24859) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAV61nwyzigFe2auQswgEFEegWhjaAmSphDcwNMq9VtaZkLN4B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2765865 INFO (qtp1577674873-24859) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAV61nwyzigFe2auQswgEFEegWhjaAmSphDcwNMq9VtaZkLN4B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2765884 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2765884 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33487/solr ready [junit4] 2> 2765890 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765895 INFO (qtp820672542-24869) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAV61nwyzigFe2auQswgEFEegWhjaAmSphDcwNMq9VtaZkLN4B3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2765895 INFO (qtp820672542-24869) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAV61nwyzigFe2auQswgEFEegWhjaAmSphDcwNMq9VtaZkLN4B3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2765901 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2765901 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[96A69256BBD92FC4]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33487/solr ready [junit4] 2> 2765904 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires=Alh, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Alh, 01-Jan-1970 00:00:00 GMT [junit4] 2> 2765905 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify [junit4] 2> 2765918 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp [junit4] 2> 2765922 INFO (qtp1577674873-24859) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params op=someSolrOperation&action=LIST&user.name=foo&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2765922 INFO (qtp1577674873-24859) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={op=someSolrOperation&action=LIST&user.name=foo&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2765922 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[96A69256BBD92FC4]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=foo&p=foo&t=simple-dt&e=1506321653232&s=4Irb7vuRP1sqlrfLVUfmqM3sM5A="; Domain=127.0.0.1; Expires=Jtt, 25-Sep-2017 06:40:53 GMT; Secure; HttpOnly". Invalid 'expires' attribute: Jtt, 25-Sep-2017 06:40:53 GMT [junit4] 2> 2765923 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[96A69256BBD92FC4]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp [junit4] 2> 2765925 INFO (jetty-closer-3321-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@34cffae9{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 2765925 INFO (jetty-closer-3321-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@298bb3e3{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 2765927 INFO (jetty-closer-3321-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=893959232 [junit4] 2> 2765928 INFO (jetty-closer-3321-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=953181336 [junit4] 2> 2765928 INFO (jetty-closer-3321-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 2765928 INFO (jetty-closer-3321-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 2765928 INFO (jetty-closer-3321-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4c68cb7a: rootName = solr_33533, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@34c1e67c [junit4] 2> 2765928 INFO (jetty-closer-3321-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@15f64ae4: rootName = solr_46289, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@75f35819 [junit4] 2> 2765934 INFO (jetty-closer-3321-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 2765934 INFO (jetty-closer-3321-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@86e6f52: rootName = solr_46289, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2113d2e7 [junit4] 2> 2765934 INFO (jetty-closer-3321-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 2765934 INFO (jetty-closer-3321-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7c1ab37a: rootName = solr_33533, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2113d2e7 [junit4] 2> 2765939 INFO (jetty-closer-3321-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 2765939 INFO (jetty-closer-3321-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e04d107: rootName = solr_46289, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@252c521d [junit4] 2> 2765939 INFO (jetty-closer-3321-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 2765939 INFO (jetty-closer-3321-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@aa75f82: rootName = solr_33533, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@252c521d [junit4] 2> 2765940 INFO (jetty-closer-3321-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 2765940 INFO (jetty-closer-3321-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 2765940 INFO (jetty-closer-3321-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2765940 INFO (jetty-closer-3321-thread-2) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2765940 INFO (jetty-closer-3321-thread-2) [ ] o.a.s.c.Overseer Overseer (id=98715936521519110-127.0.0.1:46289_solr-n_0000000000) closing [junit4] 2> 2765941 INFO (OverseerStateUpdate-98715936521519110-127.0.0.1:46289_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:46289_solr [junit4] 2> 2765941 INFO (zkCallback-3332-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 2765944 WARN (zkCallback-3332-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 2765944 ERROR (Thread[Thread-7773,5,TGRP-TestDelegationWithHadoopAuth]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted [junit4] 2> 2765951 INFO (jetty-closer-3321-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6b2fa6cd{/solr,null,UNAVAILABLE} [junit4] 2> 2767441 WARN (zkCallback-3331-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 2767441 ERROR (Thread[Thread-7771,5,TGRP-TestDelegationWithHadoopAuth]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted [junit4] 2> 2767443 INFO (jetty-closer-3321-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7bbe445a{/solr,null,UNAVAILABLE} [junit4] 2> 2767444 ERROR (SUITE-TestDelegationWithHadoopAuth-seed#[96A69256BBD92FC4]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 2767444 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[96A69256BBD92FC4]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33487 33487 [junit4] 2> 2767479 INFO (Thread-7754) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33487 33487 [junit4] 2> 2767485 WARN (Thread-7754) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 8 /solr/aliases.json [junit4] 2> 2 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 8 /solr/clusterprops.json [junit4] 2> 8 /solr/clusterstate.json [junit4] 2> 3 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_7 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_6 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_8 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_1 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_3 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_2 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_2 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_5 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_4 [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 8 /solr/live_nodes [junit4] 2> 8 /solr/collections [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot [junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_96A69256BBD92FC4-001 [junit4] 2> Sep 24, 2017 8:40:54 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=27057, maxDocsPerChunk=8, blockSize=4), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=27057, blockSize=4)), sim=RandomSimilarity(queryNorm=false): {}, locale=vun-TZ, timezone=Australia/Hobart [junit4] 2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation 9 (64-bit)/cpus=8,threads=1,free=146839976,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [TestQueryUtils, DateFieldTest, CloudMLTQParserTest, AssignTest, PrimitiveFieldTypeTest, TestExportWriter, TestReload, DistributedVersionInfoTest, TestClusterProperties, TestMaxTokenLenTokenizer, TimeZoneUtilsTest, URLClassifyProcessorTest, DistributedQueryComponentCustomSortTest, LeaderFailoverAfterPartitionTest, TestSolrCLIRunExample, ResponseHeaderTest, ResponseLogComponentTest, MultiTermTest, TestStressReorder, BadCopyFieldTest, TestCodecSupport, TestShardHandlerFactory, TestTolerantUpdateProcessorCloud, TestRebalanceLeaders, CreateCollectionCleanupTest, TestSolrDeletionPolicy2, TestReversedWildcardFilterFactory, CheckHdfsIndexTest, UniqFieldsUpdateProcessorFactoryTest, HLLSerializationTest, XsltUpdateRequestHandlerTest, PreAnalyzedFieldTest, TestHttpShardHandlerFactory, TestIndexSearcher, MoveReplicaHDFSTest, TestChildDocTransformer, TestUtils, FastVectorHighlighterTest, TestDynamicLoading, RecoveryZkTest, SimpleCollectionCreateDeleteTest, SearchHandlerTest, CoreSorterTest, TermsComponentTest, TestSuggestSpellingConverter, CoreAdminCreateDiscoverTest, AutoScalingHandlerTest, HardAutoCommitTest, TestQuerySenderListener, TestRecovery, TestHalfAndHalfDocValues, TestFoldingMultitermQuery, TestShortCircuitedRequests, SuggesterTest, PingRequestHandlerTest, TestCloudManagedSchema, CdcrRequestHandlerTest, TriLevelCompositeIdRoutingTest, SolrMetricsIntegrationTest, ClassificationUpdateProcessorTest, TestSolrXml, DateMathParserTest, TestLeaderElectionWithEmptyReplica, TestStressInPlaceUpdates, DeleteShardTest, VersionInfoTest, ShardSplitTest, HdfsBasicDistributedZkTest, DistribDocExpirationUpdateProcessorTest, ShufflingReplicaListTransformerTest, TestOrdValues, TestFieldCacheReopen, TestSolrCoreProperties, SolrCoreMetricManagerTest, TestAnalyzeInfixSuggestions, CircularListTest, TestConfigSets, MetricUtilsTest, SortByFunctionTest, AutoCommitTest, TestCloudInspectUtil, TestJmxIntegration, FacetPivotSmallTest, SolrCloudReportersTest, TestFastWriter, TolerantUpdateProcessorTest, AddBlockUpdateTest, ShowFileRequestHandlerTest, CloudExitableDirectoryReaderTest, ConcurrentDeleteAndCreateCollectionTest, DeleteLastCustomShardedReplicaTest, TestRestManager, DistributedFacetExistsSmallTest, TestInPlaceUpdatesDistrib, TestSmileRequest, BlockDirectoryTest, TestFieldSortValues, PathHierarchyTokenizerFactoryTest, TestComplexPhraseLeadingWildcard, TestTrie, SolrXmlInZkTest, SuggestComponentTest, TestEmbeddedSolrServerSchemaAPI, TestManagedStopFilterFactory, ReplaceNodeTest, BlockJoinFacetDistribTest, TestReqParamsAPI, TestSchemalessBufferedUpdates, ZkStateReaderTest, TestSystemCollAutoCreate, DocumentBuilderTest, ActionThrottleTest, HdfsThreadLeakTest, StatsComponentTest, PolyFieldTest, TestDFRSimilarityFactory, TestFieldResource, ZkSolrClientTest, TestDistributedSearch, SimpleFacetsTest, TestSolr4Spatial, SpellCheckComponentTest, TestGroupingSearch, SolrCmdDistributorTest, BasicFunctionalityTest, DirectUpdateHandlerTest, TestBadConfig, DistributedQueryElevationComponentTest, SignatureUpdateProcessorFactoryTest, DocValuesTest, TestUpdate, QueryEqualityTest, TestRemoteStreaming, SolrInfoBeanTest, IndexBasedSpellCheckerTest, TestIndexingPerformance, FieldAnalysisRequestHandlerTest, IndexSchemaTest, UpdateRequestProcessorFactoryTest, QueryParsingTest, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, TestLFUCache, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestTolerantSearch, AliasIntegrationTest, CdcrReplicationDistributedZkTest, CdcrVersionReplicationTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, CollectionStateFormat2Test, CollectionTooManyReplicasTest, DeleteInactiveReplicaTest, DeleteNodeTest, DeleteStatusTest, DistributedQueueTest, HealthCheckHandlerTest, HttpPartitionTest, LeaderElectionContextKeyTest, LeaderFailureAfterFreshStartTest, MoveReplicaHDFSUlogDirTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, PeerSyncReplicationTest, TestCloudPseudoReturnFields, TestCloudRecovery, TestCollectionsAPIViaSolrCloudCluster, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestHdfsCloudBackupRestore, TestLeaderElectionZkExpiry, TestPullReplica, TestRandomRequestDistribution, TestSSLRandomization, TestSegmentSorting, VMParamsZkACLAndCredentialsProvidersTest, HdfsRecoverLeaseTest, RuleEngineTest, BlobRepositoryCloudTest, TestBackupRepositoryFactory, TestConfigOverlay, TestConfigSetProperties, TestInfoStreamLogging, TestInitParams, TestNRTOpen, TestReloadAndDeleteDocs, TestSolrCoreSnapshots, TestHdfsBackupRestoreCore, V2StandaloneTest, InfoHandlerTest, SecurityConfHandlerTest, StatsReloadRaceTest, TestApiFramework, TestConfigsApi, TestCoreAdminApis, DistributedExpandComponentTest, DistributedFacetPivotSmallAdvancedTest, DistributedFacetPivotSmallTest, InfixSuggestersTest, ReplicaListTransformerTest, SuggestComponentContextFilterQueryTest, TestDistributedStatsComponentCardinality, TestMacroExpander, TestMacros, SmileWriterTest, TestBinaryResponseWriter, TestGraphMLResponseWriter, TestRawTransformer, TestSubQueryTransformerDistrib, TestCollationFieldDocValues, TestManagedSchema, TestManagedSchemaThreadSafety, AnalyticsMergeStrategyTest, TestReloadDeadlock, TestSimpleQParserPlugin, TestSolr4Spatial2, TestTrieFacet, TestJsonFacets, TestExactSharedStatsCache, BasicAuthStandaloneTest, PKIAuthenticationIntegrationTest, TestSha256AuthenticationProvider, TestDelegationWithHadoopAuth] [junit4] Completed [702/733 (1!)] on J2 in 2.59s, 8 tests, 1 failure, 1 skipped <<< FAILURES! [...truncated 1013 lines...] [junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamExpressionTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_3C51A5AE753A1D89-001/init-core-data-001 [junit4] 2> 77644 INFO (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 77645 INFO (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 77645 INFO (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 77645 INFO (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_3C51A5AE753A1D89-001/tempDir-001 [junit4] 2> 77646 INFO (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 77647 INFO (Thread-167) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 77647 INFO (Thread-167) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 77656 ERROR (Thread-167) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 77747 INFO (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:46779 [junit4] 2> 77753 INFO (jetty-launcher-719-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 77753 INFO (jetty-launcher-719-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 77753 INFO (jetty-launcher-719-thread-3) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 77754 INFO (jetty-launcher-719-thread-4) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 77755 INFO (jetty-launcher-719-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@711f610f{/solr,null,AVAILABLE} [junit4] 2> 77755 INFO (jetty-launcher-719-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@672c5b82{/solr,null,AVAILABLE} [junit4] 2> 77756 INFO (jetty-launcher-719-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3b21686e{SSL,[ssl, http/1.1]}{127.0.0.1:45535} [junit4] 2> 77756 INFO (jetty-launcher-719-thread-2) [ ] o.e.j.s.Server Started @79905ms [junit4] 2> 77756 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45535} [junit4] 2> 77757 ERROR (jetty-launcher-719-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 77757 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 77757 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 77757 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 77757 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-24T20:43:32.967159Z [junit4] 2> 77758 INFO (jetty-launcher-719-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@44acbae{/solr,null,AVAILABLE} [junit4] 2> 77758 INFO (jetty-launcher-719-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@128568f5{/solr,null,AVAILABLE} [junit4] 2> 77758 INFO (jetty-launcher-719-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6f048e29{SSL,[ssl, http/1.1]}{127.0.0.1:45419} [junit4] 2> 77758 INFO (jetty-launcher-719-thread-4) [ ] o.e.j.s.Server Started @79906ms [junit4] 2> 77758 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45419} [junit4] 2> 77758 INFO (jetty-launcher-719-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@60c497dd{SSL,[ssl, http/1.1]}{127.0.0.1:38325} [junit4] 2> 77758 INFO (jetty-launcher-719-thread-3) [ ] o.e.j.s.Server Started @79907ms [junit4] 2> 77758 INFO (jetty-launcher-719-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6dfa9381{SSL,[ssl, http/1.1]}{127.0.0.1:35863} [junit4] 2> 77758 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38325} [junit4] 2> 77758 INFO (jetty-launcher-719-thread-1) [ ] o.e.j.s.Server Started @79907ms [junit4] 2> 77758 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35863} [junit4] 2> 77759 ERROR (jetty-launcher-719-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 77759 ERROR (jetty-launcher-719-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 77759 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 77759 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 77759 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 77759 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 77759 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 77760 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 77760 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-24T20:43:32.970012Z [junit4] 2> 77760 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-24T20:43:32.970024Z [junit4] 2> 77763 ERROR (jetty-launcher-719-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 77763 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 77763 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 77763 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 77763 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-09-24T20:43:32.973480Z [junit4] 2> 77767 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 77773 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 77773 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 77775 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46779/solr [junit4] 2> 77777 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 77788 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46779/solr [junit4] 2> 77808 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46779/solr [junit4] 2> 77811 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46779/solr [junit4] 2> 77824 WARN (jetty-launcher-719-thread-4) [ ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null [junit4] 2> 77825 WARN (jetty-launcher-719-thread-3) [ ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null [junit4] 2> 77837 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 77837 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 77838 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38325_solr [junit4] 2> 77838 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45419_solr [junit4] 2> 77838 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.c.Overseer Overseer (id=98715947031724039-127.0.0.1:38325_solr-n_0000000000) starting [junit4] 2> 77839 INFO (zkCallback-735-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 77841 INFO (zkCallback-737-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 77842 WARN (jetty-launcher-719-thread-1) [ ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null [junit4] 2> 77843 WARN (jetty-launcher-719-thread-2) [ ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null [junit4] 2> 77851 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 77851 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 77852 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 77853 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35863_solr [junit4] 2> 77853 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 77856 INFO (zkCallback-737-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 77857 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45535_solr [junit4] 2> 77858 INFO (zkCallback-737-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 77868 INFO (zkCallback-742-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 77872 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38325_solr [junit4] 2> 77877 INFO (zkCallback-742-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 77881 INFO (zkCallback-737-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 77884 INFO (zkCallback-743-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (4) [junit4] 2> 77890 INFO (zkCallback-735-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 77892 INFO (zkCallback-735-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 77902 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35863.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 77911 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35863.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 77911 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35863.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 77917 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45535.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 77919 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45419.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 77925 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45535.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 77925 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45535.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 77926 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45419.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 77926 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45419.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 77988 INFO (jetty-launcher-719-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_3C51A5AE753A1D89-001/tempDir-001/node1/. [junit4] 2> 77988 INFO (jetty-launcher-719-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_3C51A5AE753A1D89-001/tempDir-001/node2/. [junit4] 2> 77988 INFO (jetty-launcher-719-thread-4) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_3C51A5AE753A1D89-001/tempDir-001/node4/. [junit4] 2> 77996 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38325.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 78004 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38325.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 78004 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38325.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 78005 INFO (jetty-launcher-719-thread-3) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_3C51A5AE753A1D89-001/tempDir-001/node3/. [junit4] 2> 78098 INFO (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 78098 INFO (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46779/solr ready [junit4] 2> 78118 INFO (qtp1552845714-1752) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 78130 INFO (OverseerThreadFactory-271-thread-1) [ ] o.a.s.c.CreateCollectionCmd Create collection collection1 [junit4] 2> 78250 INFO (OverseerStateUpdate-98715947031724039-127.0.0.1:38325_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection1_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:35863/solr", [junit4] 2> "type":"NRT"} [junit4] 2> 78252 INFO (OverseerStateUpdate-98715947031724039-127.0.0.1:38325_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"collection1_shard2_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:45419/solr", [junit4] 2> "type":"NRT"} [junit4] 2> 78489 INFO (qtp9126187-1767) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 78490 INFO (qtp9126187-1767) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 78491 INFO (qtp1849978128-1760) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 78492 INFO (qtp1849978128-1760) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 78604 INFO (zkCallback-742-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 78604 INFO (zkCallback-737-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 78605 INFO (zkCallback-737-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 78605 INFO (zkCallback-742-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 79499 INFO (qtp1849978128-1760) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 79505 INFO (qtp1849978128-1760) [ ] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test [junit4] 2> 79509 INFO (qtp9126187-1767) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 79516 INFO (qtp9126187-1767) [ ] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test [junit4] 2> 79567 WARN (qtp1849978128-1760) [ ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2) [junit4] 2> 79567 WARN (qtp1849978128-1760) [ ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3) [junit4] 2> 79568 INFO (qtp1849978128-1760) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 79568 INFO (qtp1849978128-1760) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from collection collection1, trusted=true [junit4] 2> 79568 INFO (qtp1849978128-1760) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35863.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 79569 INFO (qtp1849978128-1760) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 79569 INFO (qtp1849978128-1760) [ ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_3C51A5AE753A1D89-001/tempDir-001/node1/collection1_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_3C51A5AE753A1D89-001/tempDir-001/node1/./collection1_shard1_replica_n1/data/] [junit4] 2> 79577 WARN (qtp9126187-1767) [ ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2) [junit4] 2> 79578 WARN (qtp9126187-1767) [ ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3) [junit4] 2> 79578 INFO (qtp9126187-1767) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 79578 INFO (qtp9126187-1767) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from collection collection1, trusted=true [junit4] 2> 79578 INFO (qtp9126187-1767) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45419.solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c5f7c1 [junit4] 2> 79578 INFO (qtp9126187-1767) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 79578 INFO (qtp9126187-1767) [ ] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_3C51A5AE753A1D89-001/tempDir-001/node4/collection1_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_3C51A5AE753A1D89-001/tempDir-001/node4/./collection1_shard2_replica_n2/data/] [junit4] 2> 79609 INFO (qtp1849978128-1760) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 79609 INFO (qtp1849978128-1760) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 79610 INFO (qtp1849978128-1760) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 79610 INFO (qtp1849978128-1760) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 79611 INFO (qtp1849978128-1760) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@47e7ed0c[collection1_shard1_replica_n1] main] [junit4] 2> 79612 INFO (qtp1849978128-1760) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 79612 INFO (qtp1849978128-1760) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 79612 INFO (qtp1849978128-1760) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 79613 INFO (searcherExecutor-280-thread-1) [ ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@47e7ed0c[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 79613 INFO (qtp1849978128-1760) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1579455154563842048 [junit4] 2> 79616 INFO (qtp1849978128-1760) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 79616 INFO (qtp1849978128-1760) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 79616 INFO (qtp1849978128-1760) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:35863/solr/collection1_shard1_replica_n1/ [junit4] 2> 79616 INFO (qtp1849978128-1760) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 79616 INFO (qtp1849978128-1760) [ ] o.a.s.c.SyncStrategy https://127.0.0.1:35863/solr/collection1_shard1_replica_n1/ has no replicas [junit4] 2> 79616 INFO (qtp1849978128-1760) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 79618 INFO (qtp1849978128-1760) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:35863/solr/collection1_shard1_replica_n1/ shard1 [junit4] 2> 79622 INFO (qtp9126187-1767) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 79622 INFO (qtp9126187-1767) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 79623 INFO (qtp9126187-1767) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 79623 INFO (qtp9126187-1767) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 79624 INFO (qtp9126187-1767) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@19063318[collection1_shard2_replica_n2] main] [junit4] 2> 79625 INFO (qtp9126187-1767) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 79625 INFO (qtp9126187-1767) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 79626 INFO (qtp9126187-1767) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 79627 INFO (searcherExecutor-281-thread-1) [ ] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@19063318[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 79627 INFO (qtp9126187-1767) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1579455154578522112 [junit4] 2> 79630 INFO (qtp9126187-1767) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 79630 INFO (qtp9126187-1767) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 79631 INFO (qtp9126187-1767) [ ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:45419/solr/collection1_shard2_replica_n2/ [junit4] 2> 79631 INFO (qtp9126187-1767) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 79631 INFO (qtp9126187-1767) [ ] o.a.s.c.SyncStrategy https://127.0.0.1:45419/solr/collection1_shard2_replica_n2/ has no replicas [junit4] 2> 79631 INFO (qtp9126187-1767) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 79633 INFO (qtp9126187-1767) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:45419/solr/collection1_shard2_replica_n2/ shard2 [junit4] 2> 79734 INFO (zkCallback-742-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 79734 INFO (zkCallback-737-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 79734 INFO (zkCallback-737-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 79734 INFO (zkCallback-742-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 79769 INFO (qtp1849978128-1760) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 79770 INFO (qtp1849978128-1760) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1278 [junit4] 2> 79784 INFO (qtp9126187-1767) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 79785 INFO (qtp9126187-1767) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1295 [junit4] 2> 79789 INFO (qtp1552845714-1752) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 79887 INFO (zkCallback-737-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 79887 INFO (zkCallback-737-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 79887 INFO (zkCallback-742-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 79887 INFO (zkCallback-742-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4]) [junit4] 2> 80131 INFO (OverseerCollectionConfigSetProcessor-98715947031724039-127.0.0.1:38325_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 80790 INFO (qtp1552845714-1752) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2671 [junit4] 2> 80790 INFO (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):90 [junit4] 2> 80791 INFO (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1 [junit4] 2> 80806 INFO (TEST-StreamExpressionTest.testCellStream-seed#[3C51A5AE753A1D89]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCellStream [junit4] 2> 80818 INFO (qtp1849978128-1761) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:45419/solr/collection1_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:* (-1579455155824230400)} 0 2 [junit4] 2> 80823 INFO (qtp9126187-1753) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1579455155832619008,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 80823 INFO (qtp1849978128-1760) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1579455155832619008,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 80824 INFO (qtp9126187-1753) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 80824 INFO (qtp1849978128-1760) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 80825 INFO (qtp1849978128-1760) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 80825 INFO (qtp9126187-1753) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 80825 INFO (qtp1849978128-1760) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:45419/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1 [junit4] 2> 80825 INFO (qtp9126187-1753) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:45419/solr/collection1_shard2_replica_n2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1 [junit4] 2> 80825 INFO (qtp9126187-1767) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1579455155821084672),commit=} 0 14 [junit4] 2> 80829 INFO (qtp9126187-1755) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1:4&wt=javabin&version=2}{add=[hello (1579455155836813312), hello1 (1579455155837861888)]} 0 2 [junit4] 2> 80831 INFO (qtp1849978128-1763) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1579455155841007616,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 80831 INFO (qtp9126187-1758) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1579455155841007616,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 80831 INFO (qtp1849978128-1763) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 80832 INFO (qtp [...truncated too long message...] ServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15eb5a17c7e0007, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 178141 INFO (zkCallback-737-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45419_solr [junit4] 2> 178141 INFO (zkCallback-737-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 178145 INFO (coreCloseExecutor-472-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1.shard2.leader, tag=1862020634 [junit4] 2> 178145 INFO (coreCloseExecutor-472-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.mainCorpus.shard2.leader, tag=1675495148 [junit4] 2> 178145 INFO (coreCloseExecutor-472-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.workQueue.shard1.leader, tag=1121531831 [junit4] 2> 178148 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15eb5a17c7e0008, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 179633 WARN (zkCallback-742-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 179633 INFO (jetty-closer-720-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@672c5b82{/solr,null,UNAVAILABLE} [junit4] 2> 179636 WARN (zkCallback-743-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 179636 INFO (jetty-closer-720-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@711f610f{/solr,null,UNAVAILABLE} [junit4] 2> 179641 WARN (zkCallback-735-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 179641 INFO (jetty-closer-720-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@128568f5{/solr,null,UNAVAILABLE} [junit4] 2> 179648 WARN (zkCallback-737-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 179648 INFO (jetty-closer-720-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@44acbae{/solr,null,UNAVAILABLE} [junit4] 2> 179649 ERROR (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 179649 INFO (SUITE-StreamExpressionTest-seed#[3C51A5AE753A1D89]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46779 46779 [junit4] 2> 184685 INFO (Thread-167) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46779 46779 [junit4] 2> 184686 WARN (Thread-167) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 93 /solr/aliases.json [junit4] 2> 14 /solr/configs/conf [junit4] 2> 7 /solr/configs/ml [junit4] 2> 4 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 93 /solr/clusterprops.json [junit4] 2> 93 /solr/clusterstate.json [junit4] 2> 40 /solr/collections/destinationCollection/state.json [junit4] 2> 28 /solr/collections/parallelDestinationCollection1/state.json [junit4] 2> 20 /solr/collections/destination/state.json [junit4] 2> 20 /solr/collections/parallelDestinationCollection/state.json [junit4] 2> 20 /solr/collections/workQueue/state.json [junit4] 2> 20 /solr/collections/mainCorpus/state.json [junit4] 2> 10 /solr/collections/collection1/state.json [junit4] 2> 10 /solr/collections/uknownCollection/state.json [junit4] 2> 10 /solr/collections/modelCollection/state.json [junit4] 2> 10 /solr/collections/checkpointCollection/state.json [junit4] 2> 2 /solr/overseer_elect/election/98715947031724040-127.0.0.1:45419_solr-n_0000000001 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 93 /solr/live_nodes [junit4] 2> 93 /solr/collections [junit4] 2> [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.io.stream.StreamExpressionTest_3C51A5AE753A1D89-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name_s=PostingsFormat(name=Direct), expr_s=FSTOrd50, terms_ss=Lucene50(blocksize=128), join2_s=Lucene50(blocksize=128), field_s=PostingsFormat(name=LuceneFixedGap), multiDefault=Lucene50(blocksize=128), subject=Lucene50(blocksize=128), a1_s=Lucene50(blocksize=128), a2_s=PostingsFormat(name=Direct), level1_s=FSTOrd50, body_t=PostingsFormat(name=Direct), s_multi=PostingsFormat(name=Direct), col_s=Lucene50(blocksize=128), a_ss=PostingsFormat(name=LuceneFixedGap), level2_s=PostingsFormat(name=LuceneFixedGap), a_s=PostingsFormat(name=Direct), tv_text=PostingsFormat(name=LuceneFixedGap), term_s=Lucene50(blocksize=128), a_t=PostingsFormat(name=LuceneFixedGap), text_s=FSTOrd50, id=FSTOrd50, text=PostingsFormat(name=Direct), featureSet_s=Lucene50(blocksize=128), checkpoint_ss=PostingsFormat(name=Direct), test_t=PostingsFormat(name=Direct), ident_s=Lucene50(blocksize=128), whitetok=PostingsFormat(name=Direct), side_s=PostingsFormat(name=Direct)}, docValues:{name_s=DocValuesFormat(name=Asserting), expr_s=DocValuesFormat(name=Memory), iteration_i=DocValuesFormat(name=Memory), terms_ss=DocValuesFormat(name=Lucene70), join2_s=DocValuesFormat(name=Lucene70), multiDefault=DocValuesFormat(name=Lucene70), intDefault=DocValuesFormat(name=Direct), a_f=DocValuesFormat(name=Lucene70), falseNegative_i=DocValuesFormat(name=Direct), a2_s=DocValuesFormat(name=Asserting), a_i=DocValuesFormat(name=Memory), level1_s=DocValuesFormat(name=Memory), a_s=DocValuesFormat(name=Asserting), id=DocValuesFormat(name=Memory), alpha_d=DocValuesFormat(name=Asserting), i_multi=DocValuesFormat(name=Memory), idf_d=DocValuesFormat(name=Lucene70), b_ls=DocValuesFormat(name=Lucene70), checkpoint_ss=DocValuesFormat(name=Asserting), ident_s=DocValuesFormat(name=Lucene70), order_i=DocValuesFormat(name=Lucene70), error_d=DocValuesFormat(name=Memory), side_s=DocValuesFormat(name=Asserting), truePositive_i=DocValuesFormat(name=Memory), miles_i=DocValuesFormat(name=Lucene70), field_s=DocValuesFormat(name=Direct), price_f=DocValuesFormat(name=Lucene70), a1_s=DocValuesFormat(name=Lucene70), join1_i=DocValuesFormat(name=Memory), test_i=DocValuesFormat(name=Lucene70), join3_i=DocValuesFormat(name=Asserting), falsePositive_i=DocValuesFormat(name=Direct), field_i=DocValuesFormat(name=Lucene70), s_multi=DocValuesFormat(name=Asserting), col_s=DocValuesFormat(name=Lucene70), a_ss=DocValuesFormat(name=Direct), level2_s=DocValuesFormat(name=Direct), score_f=DocValuesFormat(name=Asserting), term_s=DocValuesFormat(name=Lucene70), text_s=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Direct), idfs_ds=DocValuesFormat(name=Direct), weights_ds=DocValuesFormat(name=Asserting), featureSet_s=DocValuesFormat(name=Lucene70), trueNegative_i=DocValuesFormat(name=Asserting), index_i=DocValuesFormat(name=Lucene70), test_dt=DocValuesFormat(name=Memory), out_i=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=702, maxMBSortInHeap=6.6611722502205915, sim=RandomSimilarity(queryNorm=false): {}, locale=smn, timezone=Atlantic/Madeira [junit4] 2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation 9 (64-bit)/cpus=8,threads=1,free=300912792,total=518979584 [junit4] 2> NOTE: All tests run in this JVM: [RawValueEvaluatorTest, TestBatchUpdate, SolrExceptionTest, JdbcTest, SquareRootEvaluatorTest, HyperbolicTangentEvaluatorTest, TestV1toV2ApiMapper, LessThanEvaluatorTest, NaturalLogEvaluatorTest, ArcSineEvaluatorTest, CubedRootEvaluatorTest, DocumentAnalysisResponseTest, ConcurrentUpdateSolrClientBuilderTest, StreamExpressionParserTest, TestPolicy, AndEvaluatorTest, ShardParamsTest, TestJavaBinCodec, GraphExpressionTest, TestZkConfigManager, JettyWebappTest, SolrExampleJettyTest, TestConfigSetAdminRequest, TestClusteringResponse, SolrExampleStreamingBinaryTest, SolrExampleBinaryTest, SolrExampleXMLTest, SolrExampleEmbeddedTest, SolrParamTest, MergeIndexesEmbeddedTest, TestDocumentObjectBinder, TestHash, ModifiableSolrParamsTest, TestXMLEscaping, SolrDocumentTest, FieldAnalysisResponseTest, NamedListTest, CollectionAdminRequestRequiredParamsTest, SolrSchemalessExampleTest, BasicHttpSolrClientTest, HttpSolrClientBuilderTest, HttpSolrClientConPoolTest, LBHttpSolrClientBuilderTest, JdbcDriverTest, StreamExpressionTest] [junit4] Completed [145/145 (1!)] on J2 in 107.06s, 111 tests, 1 error <<< FAILURES! [...truncated 38587 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org