You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/04/27 14:19:05 UTC
[JENKINS] Lucene-Solr-8.x-Solaris (64bit/jdk1.8.0) - Build # 100 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Solaris/100/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
3 tests failed.
FAILED: org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain
Error Message:
Failed waiting for 3 callbacks after 30 seconds
Stack Trace:
java.lang.AssertionError: Failed waiting for 3 callbacks after 30 seconds
at __randomizedtesting.SeedInfo.seed([86F66A89366B8EB2:37E995AD86A81D6D]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:229)
at org.apache.solr.security.AuditLoggerIntegrationTest.waitForAuditEventCallbacks(AuditLoggerIntegrationTest.java:221)
at org.apache.solr.security.AuditLoggerIntegrationTest.assertThreeAdminEvents(AuditLoggerIntegrationTest.java:252)
at org.apache.solr.security.AuditLoggerIntegrationTest.testAsyncQueueDrain(AuditLoggerIntegrationTest.java:132)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.security.AuditLoggerIntegrationTest.testSynchronous
Error Message:
expected:<3> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<3> but was:<2>
at __randomizedtesting.SeedInfo.seed([86F66A89366B8EB2:31402C081AB2726F]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.security.AuditLoggerIntegrationTest.assertThreeAdminEvents(AuditLoggerIntegrationTest.java:253)
at org.apache.solr.security.AuditLoggerIntegrationTest.testSynchronous(AuditLoggerIntegrationTest.java:100)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelCommitStream
Error Message:
expected:<5> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<5> but was:<2>
at __randomizedtesting.SeedInfo.seed([EF590DA8BF3D725:2E1FF2DA17B23A69]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelCommitStream(StreamDecoratorTest.java:3309)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13878 lines...]
[junit4] Suite: org.apache.solr.security.AuditLoggerIntegrationTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.AuditLoggerIntegrationTest_86F66A89366B8EB2-001/init-core-data-001
[junit4] 2> 1318384 WARN (SUITE-AuditLoggerIntegrationTest-seed#[86F66A89366B8EB2]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1318384 INFO (SUITE-AuditLoggerIntegrationTest-seed#[86F66A89366B8EB2]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1318385 INFO (SUITE-AuditLoggerIntegrationTest-seed#[86F66A89366B8EB2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1318385 INFO (SUITE-AuditLoggerIntegrationTest-seed#[86F66A89366B8EB2]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1318389 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAsyncQueueDrain
[junit4] 2> 1318391 INFO (auditTestCallback-6722-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Listening for audit callbacks on on port 44426
[junit4] 2> 1318392 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.AuditLoggerIntegrationTest_86F66A89366B8EB2-001/tempDir-001
[junit4] 2> 1318392 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1318392 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1318392 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1318492 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer start zk server on port:37880
[junit4] 2> 1318492 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37880
[junit4] 2> 1318492 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37880
[junit4] 2> 1318506 INFO (zkConnectionManagerCallback-7025-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1318511 INFO (zkConnectionManagerCallback-7027-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1318513 INFO (zkConnectionManagerCallback-7029-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1318517 WARN (jetty-launcher-7030-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1318518 INFO (jetty-launcher-7030-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1318518 INFO (jetty-launcher-7030-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1318518 INFO (jetty-launcher-7030-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 1318519 INFO (jetty-launcher-7030-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1318519 INFO (jetty-launcher-7030-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1318520 INFO (jetty-launcher-7030-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1318520 INFO (jetty-launcher-7030-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5fd35b41{/solr,null,AVAILABLE}
[junit4] 2> 1318520 INFO (jetty-launcher-7030-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@688e1e31{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:50138}
[junit4] 2> 1318520 INFO (jetty-launcher-7030-thread-1) [ ] o.e.j.s.Server Started @1319266ms
[junit4] 2> 1318520 INFO (jetty-launcher-7030-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50138}
[junit4] 2> 1318521 ERROR (jetty-launcher-7030-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1318521 INFO (jetty-launcher-7030-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1318521 INFO (jetty-launcher-7030-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1318521 INFO (jetty-launcher-7030-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1318521 INFO (jetty-launcher-7030-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1318521 INFO (jetty-launcher-7030-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-27T13:03:51.821Z
[junit4] 2> 1318524 INFO (zkConnectionManagerCallback-7032-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1318524 INFO (jetty-launcher-7030-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1319353 INFO (jetty-launcher-7030-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1319355 WARN (jetty-launcher-7030-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2ad1cfbb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1319362 WARN (jetty-launcher-7030-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@b5c687e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1319363 INFO (jetty-launcher-7030-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37880/solr
[junit4] 2> 1319365 INFO (zkConnectionManagerCallback-7039-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1319380 INFO (zkConnectionManagerCallback-7041-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1319509 INFO (jetty-launcher-7030-thread-1) [n:127.0.0.1:50138_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50138_solr
[junit4] 2> 1319509 INFO (jetty-launcher-7030-thread-1) [n:127.0.0.1:50138_solr ] o.a.s.c.Overseer Overseer (id=72153676191432709-127.0.0.1:50138_solr-n_0000000000) starting
[junit4] 2> 1319522 INFO (zkConnectionManagerCallback-7048-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1319536 INFO (jetty-launcher-7030-thread-1) [n:127.0.0.1:50138_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37880/solr ready
[junit4] 2> 1319542 INFO (OverseerStateUpdate-72153676191432709-127.0.0.1:50138_solr-n_0000000000) [n:127.0.0.1:50138_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50138_solr
[junit4] 2> 1319544 INFO (jetty-launcher-7030-thread-1) [n:127.0.0.1:50138_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50138_solr
[junit4] 2> 1319546 INFO (zkCallback-7040-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1319547 INFO (jetty-launcher-7030-thread-1) [n:127.0.0.1:50138_solr ] o.a.s.c.CoreContainer Initializing auditlogger plugin: solr.CallbackAuditLoggerPlugin
[junit4] 2> 1319656 INFO (jetty-launcher-7030-thread-1) [n:127.0.0.1:50138_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1319686 INFO (jetty-launcher-7030-thread-1) [n:127.0.0.1:50138_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50138.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1319694 INFO (jetty-launcher-7030-thread-1) [n:127.0.0.1:50138_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50138.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1319694 INFO (jetty-launcher-7030-thread-1) [n:127.0.0.1:50138_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50138.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1319695 INFO (jetty-launcher-7030-thread-1) [n:127.0.0.1:50138_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.AuditLoggerIntegrationTest_86F66A89366B8EB2-001/tempDir-001/node1/.
[junit4] 2> 1319763 INFO (zkCallback-7047-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1319803 INFO (zkConnectionManagerCallback-7055-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1319805 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1319806 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37880/solr ready
[junit4] 2> 1319811 INFO (qtp1378149791-21174) [n:127.0.0.1:50138_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1319811 INFO (qtp1378149791-21174) [n:127.0.0.1:50138_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1319820 INFO (audit-7049-thread-1-processing-n:127.0.0.1:50138_solr) [n:127.0.0.1:50138_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sleeping for 150ms before sending callback
[junit4] 2> 1319820 INFO (qtp1378149791-21175) [n:127.0.0.1:50138_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1319827 INFO (qtp1378149791-21175) [n:127.0.0.1:50138_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 1319828 INFO (qtp1378149791-21176) [n:127.0.0.1:50138_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1319839 INFO (qtp1378149791-21176) [n:127.0.0.1:50138_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 1319841 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1225449971
[junit4] 2> 1319842 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:50138_solr
[junit4] 2> 1319842 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1319842 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:50138_solr as DOWN
[junit4] 2> 1319844 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1319845 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1319845 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6fc4ea62: rootName = solr_50138, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4e94d71
[junit4] 2> 1319850 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1319850 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@656b6097: rootName = solr_50138, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2200edcd
[junit4] 2> 1319852 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1319852 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@588f8fee: rootName = solr_50138, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7b614402
[junit4] 2> 1319852 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1319853 INFO (closeThreadPool-7060-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72153676191432709-127.0.0.1:50138_solr-n_0000000000) closing
[junit4] 2> 1319859 INFO (OverseerAutoScalingTriggerThread-72153676191432709-127.0.0.1:50138_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1319861 INFO (OverseerStateUpdate-72153676191432709-127.0.0.1:50138_solr-n_0000000000) [n:127.0.0.1:50138_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50138_solr
[junit4] 2> 1319882 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72153676191432709-127.0.0.1:50138_solr-n_0000000000) closing
[junit4] 2> 1319882 INFO (closeThreadPool-7060-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72153676191432709-127.0.0.1:50138_solr-n_0000000000) closing
[junit4] 2> 1319883 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.s.AuditLoggerPlugin Async auditlogger queue still has 2 elements, sleeping to let it drain...
[junit4] 2> 1320034 INFO (audit-7049-thread-1-processing-n:127.0.0.1:50138_solr) [n:127.0.0.1:50138_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback {"requestUrl":"http://127.0.0.1:50138/solr/admin/collections","nodeName":"n:127.0.0.1:50138_solr","message":"Completed","level":"INFO","date":1556370233113,"clientIp":"127.0.0.1","collections":[],"headers":{"User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:50138"},"solrParams":{"action":["LIST"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":50138,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=LIST&wt=javabin&version=2","eventType":"COMPLETED","requestType":"ADMIN","status":200,"qtime":2.0} to localhost:44426
[junit4] 2> 1320034 INFO (audit-7049-thread-1-processing-n:127.0.0.1:50138_solr) [n:127.0.0.1:50138_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sleeping for 150ms before sending callback
[junit4] 2> 1320130 INFO (auditTestCallback-6722-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Received audit event for path /admin/collections
[junit4] 2> 1320197 INFO (audit-7049-thread-1-processing-n:127.0.0.1:50138_solr) [n:127.0.0.1:50138_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback {"requestUrl":"http://127.0.0.1:50138/solr/admin/collections","nodeName":"n:127.0.0.1:50138_solr","message":"Completed","level":"INFO","date":1556370233127,"clientIp":"127.0.0.1","collections":[],"headers":{"User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:50138"},"solrParams":{"action":["CLUSTERSTATUS"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":50138,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=CLUSTERSTATUS&wt=javabin&version=2","eventType":"COMPLETED","requestType":"ADMIN","status":200,"qtime":6.0} to localhost:44426
[junit4] 2> 1320197 INFO (audit-7049-thread-1-processing-n:127.0.0.1:50138_solr) [n:127.0.0.1:50138_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sleeping for 150ms before sending callback
[junit4] 2> 1320242 INFO (auditTestCallback-6722-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Received audit event for path /admin/collections
[junit4] 2> 1320357 INFO (audit-7049-thread-1-processing-n:127.0.0.1:50138_solr) [n:127.0.0.1:50138_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback {"requestUrl":"http://127.0.0.1:50138/solr/admin/collections","nodeName":"n:127.0.0.1:50138_solr","message":"Completed","level":"INFO","date":1556370233139,"clientIp":"127.0.0.1","collections":[],"headers":{"User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:50138"},"solrParams":{"action":["OVERSEERSTATUS"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":50138,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=OVERSEERSTATUS&wt=javabin&version=2","eventType":"COMPLETED","requestType":"ADMIN","status":200,"qtime":10.0} to localhost:44426
[junit4] 2> 1320369 INFO (auditTestCallback-6722-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Received audit event for path /admin/collections
[junit4] 2> 1320884 INFO (jetty-closer-7056-thread-1) [ ] o.a.s.s.AuditLoggerPlugin Shutting down async Auditlogger background thread(s)
[junit4] 2> 1320884 WARN (audit-7049-thread-1-processing-n:127.0.0.1:50138_solr) [n:127.0.0.1:50138_solr ] o.a.s.s.AuditLoggerPlugin Interrupted while waiting for next audit log event
[junit4] 2> 1320885 INFO (jetty-closer-7056-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@688e1e31{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1320885 INFO (jetty-closer-7056-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5fd35b41{/solr,null,UNAVAILABLE}
[junit4] 2> 1320885 INFO (jetty-closer-7056-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1320887 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1320890 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> 1320894 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37880
[junit4] 2> 1320894 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37880
[junit4] 2> 1320895 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1320895 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37880
[junit4] 2> 1320895 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37880
[junit4] 2> 1320895 INFO (TEST-AuditLoggerIntegrationTest.testAsyncQueueDrain-seed#[86F66A89366B8EB2]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAsyncQueueDrain
[junit4] 2> 1320899 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting auth
[junit4] 2> 1320900 INFO (auditTestCallback-6737-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Listening for audit callbacks on on port 54666
[junit4] 2> 1320901 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.AuditLoggerIntegrationTest_86F66A89366B8EB2-001/tempDir-002
[junit4] 2> 1320901 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1320901 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1320901 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1321001 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer start zk server on port:59321
[junit4] 2> 1321001 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:59321
[junit4] 2> 1321001 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 59321
[junit4] 2> 1321016 INFO (zkConnectionManagerCallback-7063-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1321022 INFO (zkConnectionManagerCallback-7065-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1321026 INFO (zkConnectionManagerCallback-7067-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1321035 WARN (jetty-launcher-7068-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1321035 INFO (jetty-launcher-7068-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1321035 INFO (jetty-launcher-7068-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1321035 INFO (jetty-launcher-7068-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 1321036 INFO (jetty-launcher-7068-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1321036 INFO (jetty-launcher-7068-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1321036 INFO (jetty-launcher-7068-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1321036 INFO (jetty-launcher-7068-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3d59efb{/solr,null,AVAILABLE}
[junit4] 2> 1321037 INFO (jetty-launcher-7068-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@44549eae{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:59718}
[junit4] 2> 1321037 INFO (jetty-launcher-7068-thread-1) [ ] o.e.j.s.Server Started @1321784ms
[junit4] 2> 1321037 INFO (jetty-launcher-7068-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59718}
[junit4] 2> 1321037 ERROR (jetty-launcher-7068-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1321037 INFO (jetty-launcher-7068-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1321037 INFO (jetty-launcher-7068-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1321037 INFO (jetty-launcher-7068-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1321037 INFO (jetty-launcher-7068-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1321037 INFO (jetty-launcher-7068-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-27T13:03:54.337Z
[junit4] 2> 1321039 INFO (zkConnectionManagerCallback-7070-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1321040 INFO (jetty-launcher-7068-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1321842 INFO (jetty-launcher-7068-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1321844 WARN (jetty-launcher-7068-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5f38152e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1321848 WARN (jetty-launcher-7068-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@eba18a1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1321849 INFO (jetty-launcher-7068-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59321/solr
[junit4] 2> 1321851 INFO (zkConnectionManagerCallback-7077-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1321855 INFO (zkConnectionManagerCallback-7079-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1321954 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59718_solr
[junit4] 2> 1321955 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.c.Overseer Overseer (id=72153676355928069-127.0.0.1:59718_solr-n_0000000000) starting
[junit4] 2> 1321962 INFO (zkConnectionManagerCallback-7086-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1322002 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59321/solr ready
[junit4] 2> 1322005 INFO (OverseerStateUpdate-72153676355928069-127.0.0.1:59718_solr-n_0000000000) [n:127.0.0.1:59718_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:59718_solr
[junit4] 2> 1322005 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59718_solr
[junit4] 2> 1322007 INFO (zkCallback-7085-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1322009 INFO (OverseerStateUpdate-72153676355928069-127.0.0.1:59718_solr-n_0000000000) [n:127.0.0.1:59718_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1322010 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 1322050 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 1322076 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.c.CoreContainer Initializing auditlogger plugin: solr.CallbackAuditLoggerPlugin
[junit4] 2> 1322108 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1322134 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59718.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1322144 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59718.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1322145 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59718.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1322146 INFO (jetty-launcher-7068-thread-1) [n:127.0.0.1:59718_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.AuditLoggerIntegrationTest_86F66A89366B8EB2-001/tempDir-002/node1/.
[junit4] 2> 1322167 INFO (zkConnectionManagerCallback-7092-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1322169 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1322170 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59321/solr ready
[junit4] 2> 1322179 INFO (qtp1144274877-21248) [n:127.0.0.1:59718_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1322179 INFO (qtp1144274877-21248) [n:127.0.0.1:59718_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1322186 INFO (qtp1144274877-21248) [n:127.0.0.1:59718_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback {"requestUrl":"http://127.0.0.1:59718/solr/admin/collections","nodeName":"n:127.0.0.1:59718_solr","message":"Completed","level":"INFO","date":1556370235479,"clientIp":"127.0.0.1","collections":[],"headers":{"User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:59718"},"solrParams":{"action":["LIST"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":59718,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=LIST&wt=javabin&version=2","eventType":"COMPLETED","requestType":"ADMIN","status":200,"qtime":2.0} to localhost:54666
[junit4] 2> 1322191 INFO (qtp1144274877-21249) [n:127.0.0.1:59718_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1322191 INFO (qtp1144274877-21249) [n:127.0.0.1:59718_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1322193 INFO (auditTestCallback-6737-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Received audit event for path /admin/collections
[junit4] 2> 1322204 INFO (qtp1144274877-21249) [n:127.0.0.1:59718_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback {"requestUrl":"http://127.0.0.1:59718/solr/admin/collections","nodeName":"n:127.0.0.1:59718_solr","message":"Completed","level":"INFO","date":1556370235491,"username":"solr","clientIp":"127.0.0.1","collections":[],"headers":{"Authorization":"Basic c29scjpTb2xyUm9ja3M=","User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:59718"},"solrParams":{"action":["LIST"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":59718,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=LIST&wt=javabin&version=2","eventType":"COMPLETED","requestType":"ADMIN","status":200,"qtime":0.0} to localhost:54666
[junit4] 2> 1322205 INFO (qtp1144274877-21249) [n:127.0.0.1:59718_solr ] o.a.s.s.RuleBasedAuthorizationPlugin request has come without principal. failed permission {
[junit4] 2> "name":"collection-admin-edit",
[junit4] 2> "role":"admin"}
[junit4] 2> 1322214 INFO (qtp1144274877-21249) [n:127.0.0.1:59718_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback {"requestUrl":"http://127.0.0.1:59718/solr/admin/collections","nodeName":"n:127.0.0.1:59718_solr","message":"Rejected","level":"WARN","date":1556370235505,"clientIp":"127.0.0.1","collections":["test"],"headers":{"User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:59718"},"solrParams":{"pullReplicas":["0"],"name":["test"],"nrtReplicas":["1"],"action":["CREATE"],"numShards":["1"],"tlogReplicas":["0"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":59718,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=CREATE&name=test&numShards=1&nrtReplicas=1&pullReplicas=0&tlogReplicas=0&wt=javabin&version=2","eventType":"REJECTED","requestType":"ADMIN","status":401,"qtime":-1.0} to localhost:54666
[junit4] 2> 1322214 INFO (qtp1144274877-21249) [n:127.0.0.1:59718_solr ] o.a.s.s.HttpSolrCall USER_REQUIRED auth header null context : userPrincipal: [null] type: [ADMIN], collections: [test,], Path: [/admin/collections] path : /admin/collections params :pullReplicas=0&name=test&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2
[junit4] 2> 1322223 INFO (auditTestCallback-6737-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Received audit event for path /admin/collections
[junit4] 2> 1322227 INFO (qtp1144274877-21249) [n:127.0.0.1:59718_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback {"requestUrl":"http://127.0.0.1:59718/solr/admin/collections","nodeName":"n:127.0.0.1:59718_solr","message":"Unauthorized","level":"WARN","date":1556370235518,"clientIp":"127.0.0.1","collections":["test"],"headers":{"User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:59718"},"solrParams":{"pullReplicas":["0"],"name":["test"],"nrtReplicas":["1"],"action":["CREATE"],"numShards":["1"],"tlogReplicas":["0"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":59718,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=CREATE&name=test&numShards=1&nrtReplicas=1&pullReplicas=0&tlogReplicas=0&wt=javabin&version=2","eventType":"UNAUTHORIZED","requestType":"ADMIN","status":403,"qtime":-1.0} to localhost:54666
[junit4] 2> 1322229 INFO (auditTestCallback-6737-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Received audit event for path /admin/collections
[junit4] 2> 1322234 INFO (auditTestCallback-6737-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Received audit event for path /admin/collections
[junit4] 1> {"requestUrl":"http://127.0.0.1:59718/solr/admin/collections","nodeName":"n:127.0.0.1:59718_solr","message":"Completed","level":"INFO","date":1556370235491,"username":"solr","clientIp":"127.0.0.1","collections":[],"headers":{"Authorization":"Basic c29scjpTb2xyUm9ja3M=","User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:59718"},"solrParams":{"action":["LIST"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":59718,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=LIST&wt=javabin&version=2","eventType":"COMPLETED","requestType":"ADMIN","status":200,"qtime":0.0}
[junit4] 2> 1322256 INFO (qtp1144274877-21250) [ ] o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback {"requestUrl":"http://127.0.0.1:59718/solr/admin/collections","message":"Rejected","level":"WARN","date":1556370235543,"clientIp":"127.0.0.1","headers":{"Authorization":"Basic c29scjp3cm9uZ1BX","User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:59718"},"solrParams":{},"solrHost":"127.0.0.1","solrPort":59718,"solrIp":"127.0.0.1","resource":"/solr","httpMethod":"GET","httpQueryString":"action=CREATE&name=test&numShards=1&nrtReplicas=1&pullReplicas=0&tlogReplicas=0&wt=javabin&version=2","eventType":"REJECTED","requestType":"UNKNOWN","status":401,"qtime":-1.0} to localhost:54666
[junit4] 2> 1322258 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1433070390
[junit4] 2> 1322258 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:59718_solr
[junit4] 2> 1322259 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1322259 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:59718_solr as DOWN
[junit4] 2> 1322261 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1322262 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1322262 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@75192f96: rootName = solr_59718, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@646f562a
[junit4] 2> 1322268 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1322268 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@421f34ac: rootName = solr_59718, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2200edcd
[junit4] 2> 1322270 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1322271 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3081c9d3: rootName = solr_59718, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7b614402
[junit4] 2> 1322271 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1322272 INFO (closeThreadPool-7097-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72153676355928069-127.0.0.1:59718_solr-n_0000000000) closing
[junit4] 2> 1322273 INFO (OverseerAutoScalingTriggerThread-72153676355928069-127.0.0.1:59718_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1322274 INFO (OverseerStateUpdate-72153676355928069-127.0.0.1:59718_solr-n_0000000000) [n:127.0.0.1:59718_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:59718_solr
[junit4] 2> 1322324 INFO (jetty-closer-7093-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72153676355928069-127.0.0.1:59718_solr-n_0000000000) closing
[junit4] 2> 1322324 INFO (closeThreadPool-7097-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72153676355928069-127.0.0.1:59718_solr-n_0000000000) closing
[junit4] 2> 1322327 INFO (jetty-closer-7093-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@44549eae{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1322328 INFO (jetty-closer-7093-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3d59efb{/solr,null,UNAVAILABLE}
[junit4] 2> 1322330 INFO (jetty-closer-7093-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1322335 INFO (auditTestCallback-6737-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Received audit event for path /solr
[junit4] 2> 1322373 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1322375 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1005762e86f0001, likely client has closed socket
[junit4] 2> 1322430 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> 1322433 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:59321
[junit4] 2> 1322433 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 59321
[junit4] 2> 1322433 INFO (TEST-AuditLoggerIntegrationTest.auth-seed#[86F66A89366B8EB2]) [ ] o.a.s.SolrTestCaseJ4 ###Ending auth
[junit4] 2> 1322438 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testQueuedTimeMetric
[junit4] 2> 1322438 INFO (auditTestCallback-6752-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Listening for audit callbacks on on port 53756
[junit4] 2> 1322439 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.AuditLoggerIntegrationTest_86F66A89366B8EB2-001/tempDir-003
[junit4] 2> 1322439 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1322439 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1322440 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1322539 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer start zk server on port:36712
[junit4] 2> 1322540 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36712
[junit4] 2> 1322540 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36712
[junit4] 2> 1322545 INFO (zkConnectionManagerCallback-7099-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1322549 INFO (zkConnectionManagerCallback-7101-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1322551 INFO (zkConnectionManagerCallback-7103-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1322554 WARN (jetty-launcher-7104-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1322555 INFO (jetty-launcher-7104-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1322555 INFO (jetty-launcher-7104-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1322555 INFO (jetty-launcher-7104-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 1322556 INFO (jetty-launcher-7104-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1322556 INFO (jetty-launcher-7104-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1322556 INFO (jetty-launcher-7104-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1322556 INFO (jetty-launcher-7104-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@41983929{/solr,null,AVAILABLE}
[junit4] 2> 1322557 INFO (jetty-launcher-7104-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@302c94b3{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:55753}
[junit4] 2> 1322557 INFO (jetty-launcher-7104-thread-1) [ ] o.e.j.s.Server Started @1323304ms
[junit4] 2> 1322557 INFO (jetty-launcher-7104-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55753}
[junit4] 2> 1322557 ERROR (jetty-launcher-7104-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1322557 INFO (jetty-launcher-7104-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1322557 INFO (jetty-launcher-7104-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1322557 INFO (jetty-launcher-7104-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1322557 INFO (jetty-launcher-7104-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1322557 INFO (jetty-launcher-7104-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-27T13:03:55.857Z
[junit4] 2> 1322559 INFO (zkConnectionManagerCallback-7106-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1322560 INFO (jetty-launcher-7104-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1323556 INFO (jetty-launcher-7104-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1323557 WARN (jetty-launcher-7104-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6985739c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1323561 WARN (jetty-launcher-7104-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7497052e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1323562 INFO (jetty-launcher-7104-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36712/solr
[junit4] 2> 1323564 INFO (zkConnectionManagerCallback-7113-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1323568 INFO (zkConnectionManagerCallback-7115-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1323710 INFO (jetty-launcher-7104-thread-1) [n:127.0.0.1:55753_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55753_solr
[junit4] 2> 1323710 INFO (jetty-launcher-7104-thread-1) [n:127.0.0.1:55753_solr ] o.a.s.c.Overseer Overseer (id=72153676456787973-127.0.0.1:55753_solr-n_0000000000) starting
[junit4] 2> 1323718 INFO (zkConnectionManagerCallback-7122-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1323721 INFO (jetty-launcher-7104-thread-1) [n:127.0.0.1:55753_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36712/solr ready
[junit4] 2> 1323722 INFO (jetty-launcher-7104-thread-1) [n:127.0.0.1:55753_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55753_solr
[junit4] 2> 1323723 INFO (OverseerStateUpdate-72153676456787973-127.0.0.1:55753_solr-n_0000000000) [n:127.0.0.1:55753_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:55753_solr
[junit4] 2> 1323725 INFO (OverseerStateUpdate-72153676456787973-127.0.0.1:55753_solr-n_0000000000) [n:127.0.0.1:55753_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1323725 INFO (zkCallback-7121-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1323726 INFO (jetty-launcher-7104-thread-1) [n:127.0.0.1:55753_solr ] o.a.s.c.CoreContainer Initializing auditlogger plugin: solr.CallbackAuditLoggerPlugin
[junit4] 2> 1323752 INFO (jetty-launcher-7104-thread-1) [n:127.0.0.1:55753_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1323774 INFO (jetty-launcher-7104-thread-1) [n:127.0.0.1:55753_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55753.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1323784 INFO (jetty-launcher-7104-thread-1) [n:127.0.0.1:55753_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55753.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1323784 INFO (jetty-launcher-7104-thread-1) [n:127.0.0.1:55753_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_55753.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1323785 INFO (jetty-launcher-7104-thread-1) [n:127.0.0.1:55753_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.AuditLoggerIntegrationTest_86F66A89366B8EB2-001/tempDir-003/node1/.
[junit4] 2> 1323814 INFO (zkConnectionManagerCallback-7129-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1323815 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1323817 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36712/solr ready
[junit4] 2> 1323822 INFO (qtp1882130226-21319) [n:127.0.0.1:55753_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1323822 INFO (qtp1882130226-21319) [n:127.0.0.1:55753_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1323822 INFO (audit-7123-thread-1-processing-n:127.0.0.1:55753_solr) [n:127.0.0.1:55753_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sleeping for 100ms before sending callback
[junit4] 2> 1323823 INFO (qtp1882130226-21320) [n:127.0.0.1:55753_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1323824 INFO (qtp1882130226-21320) [n:127.0.0.1:55753_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1323825 INFO (qtp1882130226-21321) [n:127.0.0.1:55753_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1323874 INFO (qtp1882130226-21321) [n:127.0.0.1:55753_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=49
[junit4] 2> 1323932 INFO (audit-7123-thread-1-processing-n:127.0.0.1:55753_solr) [n:127.0.0.1:55753_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback {"requestUrl":"http://127.0.0.1:55753/solr/admin/collections","nodeName":"n:127.0.0.1:55753_solr","message":"Completed","level":"INFO","date":1556370237122,"clientIp":"127.0.0.1","collections":[],"headers":{"User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:55753"},"solrParams":{"action":["LIST"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":55753,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=LIST&wt=javabin&version=2","eventType":"COMPLETED","requestType":"ADMIN","status":200,"qtime":0.0} to localhost:53756
[junit4] 2> 1323932 INFO (audit-7123-thread-1-processing-n:127.0.0.1:55753_solr) [n:127.0.0.1:55753_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sleeping for 100ms before sending callback
[junit4] 2> 1324039 INFO (audit-7123-thread-1-processing-n:127.0.0.1:55753_solr) [n:127.0.0.1:55753_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback {"requestUrl":"http://127.0.0.1:55753/solr/admin/collections","nodeName":"n:127.0.0.1:55753_solr","message":"Completed","level":"INFO","date":1556370237124,"clientIp":"127.0.0.1","collections":[],"headers":{"User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:55753"},"solrParams":{"action":["CLUSTERSTATUS"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":55753,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=CLUSTERSTATUS&wt=javabin&version=2","eventType":"COMPLETED","requestType":"ADMIN","status":200,"qtime":0.0} to localhost:53756
[junit4] 2> 1324039 INFO (audit-7123-thread-1-processing-n:127.0.0.1:55753_solr) [n:127.0.0.1:55753_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sleeping for 100ms before sending callback
[junit4] 2> 1324040 INFO (auditTestCallback-6752-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Received audit event for path /admin/collections
[junit4] 2> 1324044 INFO (auditTestCallback-6752-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Received audit event for path /admin/collections
[junit4] 2> 1324198 INFO (audit-7123-thread-1-processing-n:127.0.0.1:55753_solr) [n:127.0.0.1:55753_solr ] o.a.s.s.CallbackAuditLoggerPlugin Sent audit callback {"requestUrl":"http://127.0.0.1:55753/solr/admin/collections","nodeName":"n:127.0.0.1:55753_solr","message":"Completed","level":"INFO","date":1556370237174,"clientIp":"127.0.0.1","collections":[],"headers":{"User-Agent":"Solr[org.apache.solr.client.solrj.impl.HttpSolrClient] 1.0","Connection":"keep-alive","Host":"127.0.0.1:55753"},"solrParams":{"action":["OVERSEERSTATUS"],"wt":["javabin"],"version":["2"]},"solrHost":"127.0.0.1","solrPort":55753,"solrIp":"127.0.0.1","resource":"/admin/collections","httpMethod":"GET","httpQueryString":"action=OVERSEERSTATUS&wt=javabin&version=2","eventType":"COMPLETED","requestType":"ADMIN","status":200,"qtime":49.0} to localhost:53756
[junit4] 2> 1324212 INFO (auditTestCallback-6752-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Received audit event for path /admin/collections
[junit4] 2> 1324280 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1735613062
[junit4] 2> 1324280 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:55753_solr
[junit4] 2> 1324280 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 1324280 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:55753_solr as DOWN
[junit4] 2> 1324282 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1324283 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1324283 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@495879ef: rootName = solr_55753, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6b57da69
[junit4] 2> 1324287 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1324287 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@592000bb: rootName = solr_55753, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@2200edcd
[junit4] 2> 1324289 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1324289 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2115aec9: rootName = solr_55753, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7b614402
[junit4] 2> 1324289 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1324290 INFO (closeThreadPool-7134-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72153676456787973-127.0.0.1:55753_solr-n_0000000000) closing
[junit4] 2> 1324291 INFO (OverseerAutoScalingTriggerThread-72153676456787973-127.0.0.1:55753_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1324292 INFO (OverseerStateUpdate-72153676456787973-127.0.0.1:55753_solr-n_0000000000) [n:127.0.0.1:55753_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:55753_solr
[junit4] 2> 1324297 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1005762ee720006, likely client has closed socket
[junit4] 2> 1324297 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72153676456787973-127.0.0.1:55753_solr-n_0000000000) closing
[junit4] 2> 1324297 INFO (closeThreadPool-7134-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72153676456787973-127.0.0.1:55753_solr-n_0000000000) closing
[junit4] 2> 1324298 INFO (jetty-closer-7130-thread-1) [ ] o.a.s.s.AuditLoggerPlugin Shutting down async Auditlogger background thread(s)
[junit4] 2> 1324298 WARN (audit-7123-thread-1-processing-n:127.0.0.1:55753_solr) [n:127.0.0.1:55753_solr ] o.a.s.s.AuditLoggerPlugin Interrupted while waiting for next audit log event
[junit4] 2> 1324298 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x1005762ee720005, likely client has closed socket
[junit4] 2> 1324299 INFO (jetty-closer-7130-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@302c94b3{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1324299 INFO (jetty-closer-7130-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@41983929{/solr,null,UNAVAILABLE}
[junit4] 2> 1324299 INFO (jetty-closer-7130-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1324301 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1324304 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2>
[junit4] 2> 1324310 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36712
[junit4] 2> 1324310 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36712
[junit4] 2> 1324310 INFO (TEST-AuditLoggerIntegrationTest.testQueuedTimeMetric-seed#[86F66A89366B8EB2]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testQueuedTimeMetric
[junit4] 2> 1324315 INFO (TEST-AuditLoggerIntegrationTest.searchWithException-seed#[86F66A89366B8EB2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting searchWithException
[junit4] 2> 1324315 INFO (auditTestCallback-6767-thread-1) [ ] o.a.s.s.AuditLoggerIntegrationTest Listening for audit callbacks on on port 38598
[junit4] 2> 1324316 INFO (TEST-AuditLoggerIntegrationTest.searchWithException-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.AuditLoggerIntegrationTest_86F66A89366B8EB2-001/tempDir-004
[junit4] 2> 1324316 INFO (TEST-AuditLoggerIntegrationTest.searchWithException-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1324317 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1324317 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1324418 INFO (TEST-AuditLoggerIntegrationTest.searchWithException-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer start zk server on port:39895
[junit4] 2> 1324418 INFO (TEST-AuditLoggerIntegrationTest.searchWithException-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39895
[junit4] 2> 1324418 INFO (TEST-AuditLoggerIntegrationTest.searchWithException-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39895
[junit4] 2> 1324431 INFO (zkConnectionManagerCallback-7136-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1324435 INFO (zkConnectionManagerCallback-7138-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1324437 INFO (zkConnectionManagerCallback-7140-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1324440 WARN (jetty-launcher-7141-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1324440 INFO (jetty-launcher-7141-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1324440 INFO (jetty-launcher-7141-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1324440 INFO (jetty-launcher-7141-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 1324441 INFO (jetty-launcher-7141-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1324441 INFO (jetty-launcher-7141-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1324442 INFO (jetty-launcher-7141-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1324442 INFO (jetty-launcher-7141-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@530e0cbe{/solr,null,AVAILABLE}
[junit4] 2> 1324442 INFO (jetty-launcher-7141-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@635447bb{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:57245}
[junit4] 2> 1324442 INFO (jetty-launcher-7141-thread-1) [ ] o.e.j.s.Server Started @1325191ms
[junit4] 2> 1324442 INFO (jetty-launcher-7141-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57245}
[junit4] 2> 1324442 ERROR (jetty-launcher-7141-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1324442 INFO (jetty-launcher-7141-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1324442 INFO (jetty-launcher-7141-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.1.0
[junit4] 2> 1324442 INFO (jetty-launcher-7141-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1324442 INFO (jetty-launcher-7141-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1324442 INFO (jetty-launcher-7141-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-04-27T13:03:57.742Z
[junit4] 2> 1324445 INFO (zkConnectionManagerCallback-7143-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1324445 INFO (jetty-launcher-7141-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1324524 INFO (jetty-launcher-7141-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1324526 WARN (jetty-launcher-7141-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7cc76e6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1324528 WARN (jetty-launcher-7141-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@64c32ed7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1324529 INFO (jetty-launcher-7141-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39895/solr
[junit4] 2> 1324531 INFO (zkConnectionManagerCallback-7150-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1324534 INFO (zkConnectionManagerCallback-7152-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1324643 INFO (jetty-launcher-7141-thread-1) [n:127.0.0.1:57245_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57245_solr
[junit4] 2> 1324644 INFO (jetty-launcher-7141-thread-1) [n:127.0.0.1:57245_solr ] o.a.s.c.Overseer Overseer (id=72153676579864581-127.0.0.1:57245_solr-n_0000000000) starting
[junit4] 2> 1324652 INFO (zkConnectionManagerCallback-7159-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1324656 INFO (jetty-launcher-7141-thread-1) [n:127.0.0.1:57245_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39895/solr ready
[junit4] 2> 1324658 INFO (OverseerStateUpdate-72153676579864581-127.0.0.1:57245_solr-n_0000000000) [n:127.0.0.1:57245_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:57245_solr
[junit4] 2> 1324658 INFO (jetty-launcher-7141-thread-1) [n:127.0.0.1:57245_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57245_solr
[junit4] 2> 1324660 INFO (jetty-launcher-7141-thread-1) [n:127.0.0.1:57245_solr ] o.a.s.c.CoreContainer Initializing auditlogger plugin: solr.CallbackAuditLoggerPlugin
[junit4] 2> 1324742 INFO (jetty-launcher-7141-thread-1) [n:127.0.0.1:57245_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1324762 INFO (jetty-launcher-7141-thread-1) [n:127.0.0.1:57245_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57245.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1324776 INFO (jetty-launcher-7141-thread-1) [n:127.0.0.1:57245_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57245.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1324776 INFO (jetty-launcher-7141-thread-1) [n:127.0.0.1:57245_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57245.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@623befb1
[junit4] 2> 1324777 INFO (jetty-launcher-7141-thread-1) [n:127.0.0.1:57245_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.security.AuditLoggerIntegrationTest_86F66A89366B8EB2-001/tempDir-004/node1/.
[junit4] 2> 1324778 INFO (zkCallback-7158-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1324779 INFO (OverseerStateUpdate-72153676579864581-127.0.0.1:57245_solr-n_0000000000) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1324819 INFO (zkConnectionManagerCallback-7165-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1324821 INFO (TEST-AuditLoggerIntegrationTest.searchWithException-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1324822 INFO (TEST-AuditLoggerIntegrationTest.searchWithException-seed#[86F66A89366B8EB2]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39895/solr ready
[junit4] 2> 1324826 INFO (qtp1376087185-21393) [n:127.0.0.1:57245_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&name=test&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1324830 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection test
[junit4] 2> 1324830 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/test.AUTOCREATED/protwords.txt
[junit4] 2> 1324832 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/test.AUTOCREATED/managed-schema
[junit4] 2> 1324834 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/test.AUTOCREATED/solrconfig.xml
[junit4] 2> 1324836 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/test.AUTOCREATED/synonyms.txt
[junit4] 2> 1324837 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/test.AUTOCREATED/stopwords.txt
[junit4] 2> 1324839 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/test.AUTOCREATED/params.json
[junit4] 2> 1324841 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/test.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1324843 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/test.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1324845 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/test.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1324847 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/test.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1324849 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/test.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1324851 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/test.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1324853 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/test.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1324855 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/test.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1324857 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/test.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1324860 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/test.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1324863 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/test.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1324865 INFO (OverseerThreadFactory-6780-thread-1-processing-n:127.0.0.1:57245_solr) [n:127.0.0.1:57245_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/test.AUTOCREATED/la
[...truncated too long message...]
DecoratorTest.testParallelFetchStream
[junit4] OK 0.24s J0 | StreamDecoratorTest.testLeftOuterJoinStream
[junit4] OK 0.16s J0 | StreamDecoratorTest.testHashJoinStreamWithKnownConflict
[junit4] OK 0.22s J0 | StreamDecoratorTest.testParallelHashRollupStream
[junit4] OK 0.19s J0 | StreamDecoratorTest.testSelectStream
[junit4] OK 0.11s J0 | StreamDecoratorTest.testComplementStream
[junit4] OK 0.25s J0 | StreamDecoratorTest.testParallelUniqueStream
[junit4] OK 0.31s J0 | StreamDecoratorTest.testPriorityStream
[junit4] OK 0.30s J0 | StreamDecoratorTest.testGetStreamForEOFTuple
[junit4] OK 0.18s J0 | StreamDecoratorTest.testRankStream
[junit4] OK 0.12s J0 | StreamDecoratorTest.testNullStream
[junit4] OK 2.29s J0 | StreamDecoratorTest.testCommitStream
[junit4] OK 13.8s J0 | StreamDecoratorTest.testExecutorStream
[junit4] OK 0.25s J0 | StreamDecoratorTest.testSortStream
[junit4] OK 0.13s J0 | StreamDecoratorTest.testOuterHashJoinStreamWithKnownConflict
[junit4] OK 0.18s J0 | StreamDecoratorTest.testParallelShuffleStream
[junit4] OK 4.63s J0 | StreamDecoratorTest.testParallelDaemonUpdateStream
[junit4] OK 0.14s J0 | StreamDecoratorTest.testParallelIntersectStream
[junit4] OK 0.16s J0 | StreamDecoratorTest.testParallelNullStream
[junit4] OK 0.15s J0 | StreamDecoratorTest.testTerminatingDaemonStream
[junit4] OK 0.17s J0 | StreamDecoratorTest.testHashJoinStream
[junit4] OK 0.15s J0 | StreamDecoratorTest.testHavingStream
[junit4] OK 0.12s J0 | StreamDecoratorTest.testUniqueStream
[junit4] OK 10.5s J0 | StreamDecoratorTest.testParallelExecutorStream
[junit4] OK 0.17s J0 | StreamDecoratorTest.testIntersectStream
[junit4] OK 0.16s J0 | StreamDecoratorTest.testLetStream
[junit4] OK 0.30s J0 | StreamDecoratorTest.testMergeStream
[junit4] OK 0.15s J0 | StreamDecoratorTest.testParallelReducerStream
[junit4] OK 12.5s J0 | StreamDecoratorTest.testClassifyStream
[junit4] OK 11.3s J0 | StreamDecoratorTest.testDaemonStream
[junit4] OK 2.11s J0 | StreamDecoratorTest.testUpdateStream
[junit4] OK 0.14s J0 | StreamDecoratorTest.testParallelRollupStream
[junit4] OK 0.20s J0 | StreamDecoratorTest.testParallelHavingStream
[junit4] OK 0.18s J0 | StreamDecoratorTest.testReducerStream
[junit4] OK 0.31s J0 | StreamDecoratorTest.testRollupStream
[junit4] OK 0.16s J0 | StreamDecoratorTest.testStream
[junit4] OK 0.19s J0 | StreamDecoratorTest.testParallelComplementStream
[junit4] OK 0.15s J0 | StreamDecoratorTest.testHashRollupStream
[junit4] OK 2.18s J0 | StreamDecoratorTest.testParallelUpdateStream
[junit4] OK 0.15s J0 | StreamDecoratorTest.testOuterHashJoinStream
[junit4] OK 3.22s J0 | StreamDecoratorTest.testParallelTerminatingDaemonUpdateStream
[junit4] Completed [4/5] on J0 in 83.13s, 49 tests
[junit4]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[junit4] OK 0.13s J1 | StreamDecoratorTest.testInnerJoinStream
[junit4] OK 4.12s J1 | StreamDecoratorTest.testParallelDaemonCommitStream
[junit4] OK 0.13s J1 | StreamDecoratorTest.testParallelMergeStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testFetchStream
[junit4] OK 2.11s J1 | StreamDecoratorTest.testParallelCommitStream
[junit4] OK 0.15s J1 | StreamDecoratorTest.testParallelPriorityStream
[junit4] OK 0.17s J1 | StreamDecoratorTest.testParallelRankStream
[junit4] OK 0.17s J1 | StreamDecoratorTest.testCartesianProductStream
[junit4] OK 0.15s J1 | StreamDecoratorTest.testParallelFetchStream
[junit4] OK 0.11s J1 | StreamDecoratorTest.testLeftOuterJoinStream
[junit4] OK 0.09s J1 | StreamDecoratorTest.testHashJoinStreamWithKnownConflict
[junit4] OK 0.10s J1 | StreamDecoratorTest.testParallelHashRollupStream
[junit4] OK 0.13s J1 | StreamDecoratorTest.testSelectStream
[junit4] OK 0.10s J1 | StreamDecoratorTest.testComplementStream
[junit4] OK 0.11s J1 | StreamDecoratorTest.testParallelUniqueStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testPriorityStream
[junit4] OK 0.10s J1 | StreamDecoratorTest.testGetStreamForEOFTuple
[junit4] OK 0.12s J1 | StreamDecoratorTest.testRankStream
[junit4] OK 0.11s J1 | StreamDecoratorTest.testNullStream
[junit4] OK 2.14s J1 | StreamDecoratorTest.testCommitStream
[junit4] OK 11.1s J1 | StreamDecoratorTest.testExecutorStream
[junit4] OK 0.12s J1 | StreamDecoratorTest.testSortStream
[junit4] OK 0.12s J1 | StreamDecoratorTest.testOuterHashJoinStreamWithKnownConflict
[junit4] OK 0.15s J1 | StreamDecoratorTest.testParallelShuffleStream
[junit4] OK 4.16s J1 | StreamDecoratorTest.testParallelDaemonUpdateStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testParallelIntersectStream
[junit4] OK 0.13s J1 | StreamDecoratorTest.testParallelNullStream
[junit4] OK 0.24s J1 | StreamDecoratorTest.testTerminatingDaemonStream
[junit4] OK 0.15s J1 | StreamDecoratorTest.testHashJoinStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testHavingStream
[junit4] OK 0.13s J1 | StreamDecoratorTest.testUniqueStream
[junit4] OK 10.1s J1 | StreamDecoratorTest.testParallelExecutorStream
[junit4] OK 0.16s J1 | StreamDecoratorTest.testIntersectStream
[junit4] OK 0.17s J1 | StreamDecoratorTest.testLetStream
[junit4] OK 0.19s J1 | StreamDecoratorTest.testMergeStream
[junit4] OK 0.22s J1 | StreamDecoratorTest.testParallelReducerStream
[junit4] OK 11.8s J1 | StreamDecoratorTest.testClassifyStream
[junit4] OK 11.2s J1 | StreamDecoratorTest.testDaemonStream
[junit4] OK 2.02s J1 | StreamDecoratorTest.testUpdateStream
[junit4] OK 0.16s J1 | StreamDecoratorTest.testParallelRollupStream
[junit4] OK 0.20s J1 | StreamDecoratorTest.testParallelHavingStream
[junit4] OK 0.26s J1 | StreamDecoratorTest.testReducerStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testRollupStream
[junit4] OK 0.12s J1 | StreamDecoratorTest.testStream
[junit4] OK 0.13s J1 | StreamDecoratorTest.testParallelComplementStream
[junit4] OK 0.14s J1 | StreamDecoratorTest.testHashRollupStream
[junit4] OK 2.13s J1 | StreamDecoratorTest.testParallelUpdateStream
[junit4] OK 0.17s J1 | StreamDecoratorTest.testOuterHashJoinStream
[junit4] OK 3.15s J1 | StreamDecoratorTest.testParallelTerminatingDaemonUpdateStream
[junit4] Completed [5/5] on J1 in 74.37s, 49 tests
[junit4]
[junit4] JVM J0: 1.00 .. 198.07 = 197.07s
[junit4] JVM J1: 0.99 .. 271.20 = 270.21s
[junit4] Execution time total: 4 minutes 31 seconds
[junit4] Tests summary: 5 suites, 245 tests
[junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-solrj/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-solrj/test/J1/temp])
[junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-solrj/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-solrj/test/J0/temp])
[echo] 5 slowest tests:
[junit4:tophints] 90.06s | org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[junit4:tophints] 88.90s | org.apache.solr.client.solrj.impl.CloudSolrClientTest
[junit4:tophints] 71.44s | org.apache.solr.client.solrj.impl.CloudHttp2SolrClientTest
[junit4:tophints] 50.91s | org.apache.solr.common.cloud.TestCloudCollectionsListeners
[junit4:tophints] 46.02s | org.apache.solr.common.cloud.TestCollectionStateWatchers
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 4 minutes 34 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[repro] 1/5 failed: org.apache.solr.security.AuditLoggerIntegrationTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=0e7e470ada7f779330fe8c7da110b39a5626fa3b, workspace=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris
[WARNINGS] Computing warning deltas based on reference build #99
Recording test results
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2