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/06/05 19:21:06 UTC
[JENKINS] Lucene-Solr-8.x-Solaris (64bit/jdk1.8.0) - Build # 163 -
Still Failing!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Solaris/163/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.UnloadDistributedZkTest.test
Error Message:
Error from server at http://127.0.0.1:49908: ADDREPLICA failed to create replica
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:49908: ADDREPLICA failed to create replica
at __randomizedtesting.SeedInfo.seed([1CC083359B0B5604:9494BCEF35F73BFC]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:656)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:245)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1068)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:837)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:769)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:228)
at org.apache.solr.cloud.UnloadDistributedZkTest.testCoreUnloadAndLeaders(UnloadDistributedZkTest.java:300)
at org.apache.solr.cloud.UnloadDistributedZkTest.test(UnloadDistributedZkTest.java:67)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
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 14632 lines...]
[junit4] Suite: org.apache.solr.cloud.UnloadDistributedZkTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/init-core-data-001
[junit4] 2> 2584037 WARN (SUITE-UnloadDistributedZkTest-seed#[1CC083359B0B5604]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=73 numCloses=73
[junit4] 2> 2584037 INFO (SUITE-UnloadDistributedZkTest-seed#[1CC083359B0B5604]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2584039 INFO (SUITE-UnloadDistributedZkTest-seed#[1CC083359B0B5604]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 2584039 INFO (SUITE-UnloadDistributedZkTest-seed#[1CC083359B0B5604]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2584039 INFO (SUITE-UnloadDistributedZkTest-seed#[1CC083359B0B5604]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 2584044 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2584044 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2584044 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2584144 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer start zk server on port:49898
[junit4] 2> 2584144 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49898
[junit4] 2> 2584144 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49898
[junit4] 2> 2584148 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2584159 INFO (zkConnectionManagerCallback-15905-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2584159 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2584176 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2584190 INFO (zkConnectionManagerCallback-15907-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2584191 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2584196 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2584203 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 2584227 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2584232 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2584248 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2584261 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2584287 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2584306 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2584314 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2584321 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2584328 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkTestServer put /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2584331 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 2584924 WARN (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2584925 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2584925 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2584925 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] 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> 2584926 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2584926 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2584926 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2584927 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@507c8aa7{/,null,AVAILABLE}
[junit4] 2> 2584928 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2e692910{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:64133}
[junit4] 2> 2584928 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.e.j.s.Server Started @2586308ms
[junit4] 2> 2584928 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/tempDir-001/control/data, replicaType=NRT, hostContext=/, hostPort=64133, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/control-001/cores}
[junit4] 2> 2584928 ERROR (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2584928 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2584928 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 2584928 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2584928 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2584928 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-06-05T18:36:44.147Z
[junit4] 2> 2584930 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2584934 INFO (zkConnectionManagerCallback-15909-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2584934 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2585043 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2585043 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/control-001/solr.xml
[junit4] 2> 2585049 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2585049 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2585051 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2585910 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2585912 WARN (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4161fd05[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2585916 WARN (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@e57d676[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2585917 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49898/solr
[junit4] 2> 2585918 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2585921 INFO (zkConnectionManagerCallback-15916-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2585921 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2586026 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2586030 INFO (zkConnectionManagerCallback-15918-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2586032 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2586278 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:64133_
[junit4] 2> 2586280 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.c.Overseer Overseer (id=72133930577100804-127.0.0.1:64133_-n_0000000000) starting
[junit4] 2> 2586291 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2586293 INFO (zkConnectionManagerCallback-15925-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2586294 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2586298 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49898/solr ready
[junit4] 2> 2586300 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64133_
[junit4] 2> 2586300 INFO (OverseerStateUpdate-72133930577100804-127.0.0.1:64133_-n_0000000000) [n:127.0.0.1:64133_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:64133_
[junit4] 2> 2586303 INFO (OverseerStateUpdate-72133930577100804-127.0.0.1:64133_-n_0000000000) [n:127.0.0.1:64133_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2586305 INFO (zkCallback-15924-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2586334 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2586351 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2586360 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2586360 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2586361 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [n:127.0.0.1:64133_ ] 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/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/control-001/cores
[junit4] 2> 2586380 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2586383 INFO (zkConnectionManagerCallback-15931-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2586383 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2586385 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2586387 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49898/solr ready
[junit4] 2> 2586389 INFO (qtp1176198761-45901) [n:127.0.0.1:64133_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:64133_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2586405 INFO (OverseerThreadFactory-13862-thread-1-processing-n:127.0.0.1:64133_) [n:127.0.0.1:64133_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 2586520 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2586521 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2587563 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 2587586 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 2588582 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2588617 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true
[junit4] 2> 2588617 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2588617 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 2588620 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=26.4638671875, floorSegmentMB=0.7080078125, forceMergeDeletesPctAllowed=26.508918809856752, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3746572793128484, deletesPctAllowed=25.518711961213842
[junit4] 2> 2588622 WARN (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2588680 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2588680 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2588681 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2588681 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2588682 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=67.744140625, floorSegmentMB=1.8642578125, forceMergeDeletesPctAllowed=19.383445976695658, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=21.084735103433342
[junit4] 2> 2588682 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2798e738[control_collection_shard1_replica_n1] main]
[junit4] 2> 2588685 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2588686 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2588686 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2588686 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1635526700333793280
[junit4] 2> 2588690 INFO (searcherExecutor-13867-thread-1-processing-n:127.0.0.1:64133_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@2798e738[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2588696 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 2588696 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 2588704 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2588704 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2588704 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:64133/control_collection_shard1_replica_n1/
[junit4] 2> 2588708 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2588709 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:64133/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 2588709 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72133930577100804-core_node2-n_0000000000
[junit4] 2> 2588713 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:64133/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 2588716 INFO (zkCallback-15917-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2588716 INFO (zkCallback-15917-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2588726 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2588731 INFO (qtp1176198761-45902) [n:127.0.0.1:64133_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2212
[junit4] 2> 2588738 INFO (qtp1176198761-45901) [n:127.0.0.1:64133_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2588831 INFO (zkCallback-15917-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2588831 INFO (zkCallback-15917-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2588831 INFO (zkCallback-15917-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2588833 INFO (qtp1176198761-45901) [n:127.0.0.1:64133_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:64133_&wt=javabin&version=2} status=0 QTime=2445
[junit4] 2> 2588937 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2588939 INFO (zkConnectionManagerCallback-15937-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2588939 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2588941 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2588944 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49898/solr ready
[junit4] 2> 2588944 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2588945 INFO (qtp1176198761-45897) [n:127.0.0.1:64133_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2588953 INFO (OverseerThreadFactory-13862-thread-2-processing-n:127.0.0.1:64133_) [n:127.0.0.1:64133_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 2588953 INFO (OverseerCollectionConfigSetProcessor-72133930577100804-127.0.0.1:64133_-n_0000000000) [n:127.0.0.1:64133_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2589162 WARN (OverseerThreadFactory-13862-thread-2-processing-n:127.0.0.1:64133_) [n:127.0.0.1:64133_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 2589165 INFO (qtp1176198761-45897) [n:127.0.0.1:64133_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2589166 INFO (qtp1176198761-45897) [n:127.0.0.1:64133_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2} status=0 QTime=221
[junit4] 2> 2589167 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 2589406 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-1-001 of type NRT
[junit4] 2> 2589407 WARN (closeThreadPool-15938-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2589408 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2589408 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2589408 INFO (closeThreadPool-15938-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> 2589409 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2589409 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2589409 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2589410 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cc200e2{/,null,AVAILABLE}
[junit4] 2> 2589410 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@59151080{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:48471}
[junit4] 2> 2589410 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.Server Started @2590792ms
[junit4] 2> 2589410 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=48471, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-1-001/cores}
[junit4] 2> 2589410 ERROR (closeThreadPool-15938-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2589410 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2589411 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 2589411 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2589411 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2589411 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-06-05T18:36:48.630Z
[junit4] 2> 2589412 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2589415 INFO (zkConnectionManagerCallback-15940-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2589415 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2589517 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2589517 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-1-001/solr.xml
[junit4] 2> 2589522 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2589522 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2589524 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2589642 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-2-001 of type NRT
[junit4] 2> 2589656 WARN (closeThreadPool-15938-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2589656 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2589657 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2589657 INFO (closeThreadPool-15938-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 2589660 INFO (closeThreadPool-15938-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2589660 INFO (closeThreadPool-15938-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2589660 INFO (closeThreadPool-15938-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2589661 INFO (closeThreadPool-15938-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@e757f2e{/,null,AVAILABLE}
[junit4] 2> 2589661 INFO (closeThreadPool-15938-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@438bd01a{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:55428}
[junit4] 2> 2589661 INFO (closeThreadPool-15938-thread-2) [ ] o.e.j.s.Server Started @2591044ms
[junit4] 2> 2589661 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/, hostPort=55428, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-2-001/cores}
[junit4] 2> 2589661 ERROR (closeThreadPool-15938-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2589661 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2589661 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 2589662 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2589662 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2589662 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-06-05T18:36:48.881Z
[junit4] 2> 2589663 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2589665 INFO (zkConnectionManagerCallback-15943-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2589665 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2589768 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2589768 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-2-001/solr.xml
[junit4] 2> 2589773 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2589773 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2589776 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2590687 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-3-001 of type NRT
[junit4] 2> 2590687 WARN (closeThreadPool-15938-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2590687 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2590687 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2590687 INFO (closeThreadPool-15938-thread-3) [ ] 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> 2590689 INFO (closeThreadPool-15938-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2590689 INFO (closeThreadPool-15938-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2590689 INFO (closeThreadPool-15938-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2590689 INFO (closeThreadPool-15938-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@472dc0a2{/,null,AVAILABLE}
[junit4] 2> 2590689 INFO (closeThreadPool-15938-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@750dc141{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:49908}
[junit4] 2> 2590689 INFO (closeThreadPool-15938-thread-3) [ ] o.e.j.s.Server Started @2592072ms
[junit4] 2> 2590689 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/tempDir-001/jetty3, solrconfig=solrconfig.xml, hostContext=/, hostPort=49908, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-3-001/cores}
[junit4] 2> 2590690 ERROR (closeThreadPool-15938-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2590690 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2590690 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 2590690 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2590690 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2590690 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-06-05T18:36:49.909Z
[junit4] 2> 2590691 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2590693 INFO (zkConnectionManagerCallback-15946-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2590693 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2590796 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2590796 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-3-001/solr.xml
[junit4] 2> 2590801 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2590801 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2590803 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2590962 INFO (OverseerCollectionConfigSetProcessor-72133930577100804-127.0.0.1:64133_-n_0000000000) [n:127.0.0.1:64133_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2591489 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2591503 WARN (closeThreadPool-15938-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@bd5f05b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2591540 WARN (closeThreadPool-15938-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1c8880d1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2591542 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49898/solr
[junit4] 2> 2591542 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2591545 INFO (zkConnectionManagerCallback-15953-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2591545 INFO (closeThreadPool-15938-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2591670 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2591672 INFO (zkConnectionManagerCallback-15955-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2591672 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2591682 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2591691 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.c.ZkController Publish node=127.0.0.1:55428_ as DOWN
[junit4] 2> 2591697 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2591697 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55428_
[junit4] 2> 2591702 INFO (zkCallback-15924-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2591703 INFO (zkCallback-15936-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2591717 INFO (zkCallback-15917-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2591721 INFO (zkCallback-15954-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2591726 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2591733 INFO (zkConnectionManagerCallback-15962-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2591733 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2591735 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2591738 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49898/solr ready
[junit4] 2> 2591811 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2591842 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2591845 WARN (closeThreadPool-15938-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6e66807f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2591849 WARN (closeThreadPool-15938-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6481a795[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2591851 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49898/solr
[junit4] 2> 2591852 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2591855 INFO (zkConnectionManagerCallback-15969-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2591856 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2591857 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2591871 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2591872 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2591876 INFO (closeThreadPool-15938-thread-2) [n:127.0.0.1:55428_ ] 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/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-2-001/cores
[junit4] 2> 2591985 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2591989 INFO (zkConnectionManagerCallback-15971-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2591990 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2592025 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2592031 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.c.ZkController Publish node=127.0.0.1:48471_ as DOWN
[junit4] 2> 2592033 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2592033 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48471_
[junit4] 2> 2592034 INFO (zkCallback-15917-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2592034 INFO (zkCallback-15936-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2592036 INFO (zkCallback-15961-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2592037 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2592038 INFO (zkCallback-15924-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2592039 INFO (zkCallback-15970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2592040 INFO (zkCallback-15954-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2592043 INFO (zkConnectionManagerCallback-15978-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2592044 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2592046 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2592048 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49898/solr ready
[junit4] 2> 2592082 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2592121 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2592138 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2592138 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2592141 INFO (closeThreadPool-15938-thread-1) [n:127.0.0.1:48471_ ] 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/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-1-001/cores
[junit4] 2> 2592493 INFO (TEST-UnloadDistributedZkTest.test-seed#[1CC083359B0B5604]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-4-001 of type NRT
[junit4] 2> 2592494 WARN (closeThreadPool-15938-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2592494 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 2592494 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2592494 INFO (closeThreadPool-15938-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> 2592495 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2592495 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2592495 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2592495 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2dbfc68d{/,null,AVAILABLE}
[junit4] 2> 2592496 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@77fe8a5c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:64526}
[junit4] 2> 2592496 INFO (closeThreadPool-15938-thread-1) [ ] o.e.j.s.Server Started @2593880ms
[junit4] 2> 2592496 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/tempDir-001/jetty4, solrconfig=solrconfig.xml, hostContext=/, hostPort=64526, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-4-001/cores}
[junit4] 2> 2592496 ERROR (closeThreadPool-15938-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2592496 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2592496 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 2592497 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2592497 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2592497 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-06-05T18:36:51.716Z
[junit4] 2> 2592498 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2592501 INFO (zkConnectionManagerCallback-15981-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2592501 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2592618 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2592618 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-4-001/solr.xml
[junit4] 2> 2592623 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2592623 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2592624 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2592995 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2592996 WARN (closeThreadPool-15938-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4d9ff15d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2593018 WARN (closeThreadPool-15938-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@565f9995[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2593019 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49898/solr
[junit4] 2> 2593020 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2593023 INFO (zkConnectionManagerCallback-15988-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2593023 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2593144 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2593260 INFO (zkConnectionManagerCallback-15990-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2593260 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2593270 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2593279 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:64526_ as DOWN
[junit4] 2> 2593281 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2593281 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64526_
[junit4] 2> 2593283 INFO (zkCallback-15954-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2593283 INFO (zkCallback-15970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2593283 INFO (zkCallback-15961-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2593284 INFO (zkCallback-15936-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2593285 INFO (zkCallback-15989-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2593286 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2593287 INFO (zkCallback-15924-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2593291 INFO (zkCallback-15977-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2593291 INFO (zkCallback-15917-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2593292 INFO (zkConnectionManagerCallback-15997-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2593292 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2593294 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2593296 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49898/solr ready
[junit4] 2> 2593331 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2593360 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2593379 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2593379 INFO (closeThreadPool-15938-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2593390 INFO (closeThreadPool-15938-thread-1) [ ] 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/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-4-001/cores
[junit4] 2> 2593898 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2593904 WARN (closeThreadPool-15938-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7aae774b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2593921 WARN (closeThreadPool-15938-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@9f20a0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2593923 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49898/solr
[junit4] 2> 2593927 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2593931 INFO (zkConnectionManagerCallback-16004-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2593935 INFO (closeThreadPool-15938-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2594038 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2594046 INFO (zkConnectionManagerCallback-16006-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2594046 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2594066 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2594081 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.c.ZkController Publish node=127.0.0.1:49908_ as DOWN
[junit4] 2> 2594084 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2594084 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49908_
[junit4] 2> 2594088 INFO (zkCallback-15961-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2594088 INFO (zkCallback-15970-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2594088 INFO (zkCallback-15977-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2594088 INFO (zkCallback-15917-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2594088 INFO (zkCallback-15936-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2594088 INFO (zkCallback-15924-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2594089 INFO (zkCallback-15954-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2594089 INFO (zkCallback-15989-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2594090 INFO (zkCallback-15996-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2594092 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2594093 INFO (zkCallback-16005-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2594095 INFO (zkConnectionManagerCallback-16013-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2594095 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2594098 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 2594104 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49898/solr ready
[junit4] 2> 2594138 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2594185 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2594219 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2594219 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2594221 INFO (closeThreadPool-15938-thread-3) [n:127.0.0.1:49908_ ] 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/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-3-001/cores
[junit4] 2> 2594307 INFO (qtp140924681-45962) [n:127.0.0.1:48471_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:48471_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2594307 INFO (qtp140924681-45959) [n:127.0.0.1:48471_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:55428_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2594308 INFO (qtp140924681-45961) [n:127.0.0.1:48471_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:49908_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2594308 INFO (qtp140924681-45963) [n:127.0.0.1:48471_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:64526_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2594322 INFO (OverseerThreadFactory-13862-thread-4-processing-n:127.0.0.1:64133_) [n:127.0.0.1:64133_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:55428_ for creating new replica of shard shard1 for collection collection1
[junit4] 2> 2594326 INFO (OverseerThreadFactory-13862-thread-3-processing-n:127.0.0.1:64133_) [n:127.0.0.1:64133_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:48471_ for creating new replica of shard shard2 for collection collection1
[junit4] 2> 2594331 INFO (OverseerThreadFactory-13862-thread-4-processing-n:127.0.0.1:64133_) [n:127.0.0.1:64133_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2594368 INFO (qtp260590212-45971) [n:127.0.0.1:55428_ x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2594372 INFO (OverseerThreadFactory-13862-thread-3-processing-n:127.0.0.1:64133_) [n:127.0.0.1:64133_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2594388 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ x:collection1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n2&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2595432 INFO (qtp260590212-45971) [n:127.0.0.1:55428_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 2595433 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 2595512 INFO (qtp260590212-45971) [n:127.0.0.1:55428_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 2595529 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
[junit4] 2> 2595666 INFO (qtp260590212-45971) [n:127.0.0.1:55428_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2595668 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2595705 INFO (qtp260590212-45971) [n:127.0.0.1:55428_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 2595705 INFO (qtp260590212-45971) [n:127.0.0.1:55428_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2595705 INFO (qtp260590212-45971) [n:127.0.0.1:55428_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-2-001/cores/collection1_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-2-001/cores/collection1_shard1_replica_n1/data/]
[junit4] 2> 2595708 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from collection collection1, trusted=true
[junit4] 2> 2595708 INFO (qtp260590212-45971) [n:127.0.0.1:55428_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=26.4638671875, floorSegmentMB=0.7080078125, forceMergeDeletesPctAllowed=26.508918809856752, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3746572793128484, deletesPctAllowed=25.518711961213842
[junit4] 2> 2595708 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25b99cc8
[junit4] 2> 2595709 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-1-001/cores/collection1_shard2_replica_n2], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.UnloadDistributedZkTest_1CC083359B0B5604-001/shard-1-001/cores/collection1_shard2_replica_n2/data/]
[junit4] 2> 2595710 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=26.4638671875, floorSegmentMB=0.7080078125, forceMergeDeletesPctAllowed=26.508918809856752, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3746572793128484, deletesPctAllowed=25.518711961213842
[junit4] 2> 2595710 WARN (qtp260590212-45971) [n:127.0.0.1:55428_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2595711 WARN (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2595759 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2595759 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2595760 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2595760 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2595761 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=67.744140625, floorSegmentMB=1.8642578125, forceMergeDeletesPctAllowed=19.383445976695658, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=21.084735103433342
[junit4] 2> 2595761 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@457c7a26[collection1_shard2_replica_n2] main]
[junit4] 2> 2595763 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2595763 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2595764 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2595764 INFO (qtp140924681-45964) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1635526707755614208
[junit4] 2> 2595767 INFO (searcherExecutor-13909-thread-1-processing-n:127.0.0.1:48471_ x:collection1_shard2_replica_n2 c:collection1 s:shard2) [n:127.0.0.1:48471_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@457c7a26[collection1_shard2_replica_n2] main{Exita
[...truncated too long message...]
tingsFormat(name=Direct)}, docValues:{other_tl1=DocValuesFormat(name=Lucene80), range_facet_l_dv=DocValuesFormat(name=Lucene80), _version_=DocValuesFormat(name=Lucene80), intDefault=DocValuesFormat(name=Lucene80), id_i1=DocValuesFormat(name=Lucene80), a_i1=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Direct), intDvoDefault=DocValuesFormat(name=Lucene80), range_facet_l=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1791, maxMBSortInHeap=7.126980326722831, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@34748e96), locale=ms-MY, timezone=Pacific/Bougainville
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=3,threads=1,free=248068320,total=513802240
[junit4] 2> NOTE: All tests run in this JVM: [NoCacheHeaderTest, TestDeleteCollectionOnDownNodes, MetricsHandlerTest, TestTolerantSearch, BadIndexSchemaTest, TestManagedResourceStorage, TestNRTOpen, NodeMarkersRegistrationTest, TestConfigsApi, DistanceUnitsTest, MaxSizeAutoCommitTest, SignificantTermsQParserPluginTest, WrapperMergePolicyFactoryTest, TestFieldCache, CoreAdminOperationTest, TestBinaryField, AuthToolTest, TestShardHandlerFactory, ShardsWhitelistTest, MetricsConfigTest, TestExclusionRuleCollectionAccess, TestQueryUtils, SystemCollectionCompatTest, ExplicitHLLTest, DistributedQueryComponentCustomSortTest, TestOnReconnectListenerSupport, TestSubQueryTransformer, HLLSerializationTest, CloneFieldUpdateProcessorFactoryTest, TestDistributedMissingSort, URLClassifyProcessorTest, UpdateParamsTest, SolrRequestParserTest, SuggesterWFSTTest, StressHdfsTest, PreAnalyzedFieldTest, TestSolrDeletionPolicy2, NumberUtilsTest, ResponseLogComponentTest, TestRandomFlRTGCloud, TestSort, JSONWriterTest, HttpPartitionWithTlogReplicasTest, TestMissingGroups, ShardSplitTest, DirectSolrSpellCheckerTest, CollectionPropsTest, MissingSegmentRecoveryTest, NodeAddedTriggerIntegrationTest, MinimalSchemaTest, OverseerCollectionConfigSetProcessorTest, TestSchemaManager, TestCSVLoader, TestLMDirichletSimilarityFactory, TestSolrCoreParser, SampleTest, TestNumericTerms64, SolrIndexMetricsTest, DistributedUpdateProcessorTest, TestJsonFacets, TestSimNodeLostTrigger, BaseCdcrDistributedZkTest, TestFuzzyAnalyzedSuggestions, FacetPivotSmallTest, TestLegacyFieldCache, RemoteQueryErrorTest, XmlUpdateRequestHandlerTest, NodeLostTriggerIntegrationTest, AuditLoggerPluginTest, SolrSlf4jReporterTest, TestPHPSerializedResponseWriter, TestHashQParserPlugin, TestConfig, TestJsonFacetsWithNestedObjects, TestSimTriggerIntegration, TestCloudSearcherWarming, TestManagedSchemaAPI, TestLuceneMatchVersion, FieldAnalysisRequestHandlerTest, ScheduledTriggerTest, SolrCmdDistributorTest, TestAnalyzedSuggestions, DOMUtilTest, TestLRUCache, TestSimpleTrackingShardHandler, TestLockTree, SolrJmxReporterCloudTest, TestLuceneIndexBackCompat, TestCloudPivotFacet, RestartWhileUpdatingTest, TestPullReplica, TestDistributedTracing, TestNoOpRegenerator, QueryResultKeyTest, ScriptEngineTest, SegmentsInfoRequestHandlerTest, TestSolrCLIRunExample, MultiSolrCloudTestCaseTest, HDFSCollectionsAPITest, BasicZkTest, HighlighterMaxOffsetTest, TestSha256AuthenticationProvider, PeerSyncReplicationTest, TaggingAttributeTest, TestExportWriter, TriggerSetPropertiesIntegrationTest, BlockJoinFacetDistribTest, DistributedFacetPivotSmallAdvancedTest, TimeRoutedAliasUpdateProcessorTest, V2ApiIntegrationTest, TestSolrXml, RequiredFieldsTest, HttpPartitionOnCommitTest, PeerSyncWithBufferUpdatesTest, PropertiesRequestHandlerTest, TestFieldCacheVsDocValues, TestCharFilters, FullSolrCloudDistribCmdsTest, ImplicitSnitchTest, CdcrUpdateLogTest, TestManagedSynonymGraphFilterFactory, TestConfigSetsAPIZkFailure, DocValuesMultiTest, TestSQLHandlerNonCloud, IgnoreLargeDocumentProcessorFactoryTest, OverseerRolesTest, TestHdfsUpdateLog, ZkSolrClientTest, TestCloudInspectUtil, Tagger2Test, TestMacroExpander, CursorPagingTest, TermVectorComponentDistributedTest, CollectionTooManyReplicasTest, SolrMetricsIntegrationTest, TestLogWatcher, SuggesterTSTTest, HdfsChaosMonkeySafeLeaderTest, PrimUtilsTest, TestDFRSimilarityFactory, ClassificationUpdateProcessorIntegrationTest, TestSimExtremeIndexing, MoreLikeThisHandlerTest, LeaderFailoverAfterPartitionTest, TestPerFieldSimilarity, TestStressVersions, ShardRoutingTest, LargeFieldTest, TestFoldingMultitermQuery, TestStressLucene, TestSchemaSimilarityResource, TestInPlaceUpdatesDistrib, TestPostingsSolrHighlighter, TestSerializedLuceneMatchVersion, SliceStateTest, TriggerEventQueueTest, DistanceFunctionTest, TestReload, DateRangeFieldTest, HdfsUnloadDistributedZkTest, SearchRateTriggerTest, EnumFieldTest, TestDynamicLoading, TestComplexPhraseQParserPlugin, RegexBoostProcessorTest, SubstringBytesRefFilterTest, TestHalfAndHalfDocValues, ZkStateReaderTest, ConfigSetsAPITest, TestManagedStopFilterFactory, SolrCoreTest, TestConfigSets, TestStressLiveNodes, SolrShardReporterTest, TermsComponentTest, TestImplicitCoreProperties, JWTAuthPluginIntegrationTest, DistributedFacetSimpleRefinementLongTailTest, SolrTestCaseJ4Test, TestAtomicUpdateErrorCases, CdcrVersionReplicationTest, TestSimpleQParserPlugin, BlockDirectoryTest, TestPivotHelperCode, SearchHandlerTest, TestCoreDiscovery, ZkNodePropsTest, TestRemoteStreaming, TestSolrJ, TestZkChroot, UniqFieldsUpdateProcessorFactoryTest, TestLegacyBM25SimilarityFactory, MetricTriggerIntegrationTest, SpatialHeatmapFacetsTest, ReplaceNodeTest, TestSweetSpotSimilarityFactory, TestDefaultStatsCache, TestSolr4Spatial, TestBadConfig, ExecutePlanActionTest, OutOfBoxZkACLAndCredentialsProvidersTest, SolrLogAuditLoggerPluginTest, HdfsRestartWhileUpdatingTest, SplitShardTest, RecoveryZkTest, LeaderTragicEventTest, TestFilteredDocIdSet, AtomicUpdateRemovalJavabinTest, TestMaxTokenLenTokenizer, TestTolerantUpdateProcessorCloud, AnalysisAfterCoreReloadTest, BasicFunctionalityTest, ConvertedLegacyTest, DisMaxRequestHandlerTest, DistributedIntervalFacetingTest, OutputWriterTest, SolrInfoBeanTest, TestCursorMarkWithoutUniqueKey, TestDistributedGrouping, TestDocumentBuilder, TestHighlightDedupGrouping, TestJoin, TestRandomDVFaceting, TestRandomFaceting, PathHierarchyTokenizerFactoryTest, TestWordDelimiterFilterFactory, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ActionThrottleTest, AliasIntegrationTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, CloudExitableDirectoryReaderTest, ClusterStateTest, ClusterStateUpdateTest, ConcurrentCreateRoutedAliasTest, ConnectionManagerTest, CreateCollectionCleanupTest, CreateRoutedAliasTest, DeleteInactiveReplicaTest, DeleteNodeTest, DeleteShardTest, DistribCursorPagingTest, DistribJoinFromCollectionTest, DistributedQueueTest, DistributedVersionInfoTest, LeaderElectionIntegrationTest, LegacyCloudClusterPropTest, MetricsHistoryWithAuthIntegrationTest, MoveReplicaHDFSTest, MoveReplicaTest, RecoveryAfterSoftCommitTest, RollingRestartTest, RoutingToNodesWithPropertiesTest, SaslZkACLProviderTest, ShardRoutingCustomTest, SolrXmlInZkTest, TestAuthenticationFramework, TestCloudConsistency, TestConfigSetsAPIExclusivity, TestDistribDocBasedVersion, TestDistributedMap, TestDownShardTolerantSearch, TestPrepRecovery, TestSSLRandomization, TestSegmentSorting, TestShortCircuitedRequests, TestSizeLimitedDistributedMap, TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, TestSolrCloudWithSecureImpersonation, TestStressCloudBlindAtomicUpdates, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, UnloadDistributedZkTest]
[junit4] Completed [525/861 (1!)] on J1 in 61.03s, 1 test, 1 error <<< FAILURES!
[...truncated 40995 lines...]
-ecj-javadoc-lint-src:
[mkdir] Created dir: /var/tmp/ecj1498428673
[ecj-lint] Compiling 85 source files to /var/tmp/ecj1498428673
[ecj-lint] ----------
[ecj-lint] 1. ERROR in /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/lucene/sandbox/src/java/org/apache/lucene/geo/Rectangle2D.java (at line 20)
[ecj-lint] import java.util.Arrays;
[ecj-lint] ^^^^^^^^^^^^^^^^
[ecj-lint] The import java.util.Arrays is never used
[ecj-lint] ----------
[ecj-lint] 1 problem (1 error)
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/build.xml:634: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/build.xml:101: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/lucene/build.xml:207: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/lucene/common-build.xml:2270: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/lucene/common-build.xml:2093: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/lucene/common-build.xml:2132: Compile failed; see the compiler error output for details.
Total time: 110 minutes 13 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/export/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
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
[JENKINS] Lucene-Solr-8.x-Solaris (64bit/jdk1.8.0) - Build # 164 -
Still unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Solaris/164/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI
Error Message:
should be a routed alias
Stack Trace:
java.lang.AssertionError: should be a routed alias
at __randomizedtesting.SeedInfo.seed([56400A8D0B28D0A7:499796A1782329EC]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI(AliasIntegrationTest.java:305)
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 13679 lines...]
[junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/init-core-data-001
[junit4] 2> 1041307 WARN (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=92 numCloses=92
[junit4] 2> 1041307 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1041308 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1041308 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1041309 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001
[junit4] 2> 1041309 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1041309 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1041309 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1041409 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:60714
[junit4] 2> 1041409 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:60714
[junit4] 2> 1041409 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 60714
[junit4] 2> 1041420 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1041424 INFO (zkConnectionManagerCallback-8092-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1041424 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1041428 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1041429 INFO (zkConnectionManagerCallback-8094-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1041429 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1041430 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1041431 INFO (zkConnectionManagerCallback-8096-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1041432 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1041535 WARN (jetty-launcher-8097-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1041535 WARN (jetty-launcher-8097-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1041535 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1041535 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1041535 INFO (jetty-launcher-8097-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_201-b09
[junit4] 2> 1041535 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1041535 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1041535 INFO (jetty-launcher-8097-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> 1041536 INFO (jetty-launcher-8097-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1041536 INFO (jetty-launcher-8097-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1041536 INFO (jetty-launcher-8097-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1041536 INFO (jetty-launcher-8097-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@172d1dee{/solr,null,AVAILABLE}
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6b4ddd77{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:60386}
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7ef2f4bc{/solr,null,AVAILABLE}
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-1) [ ] o.e.j.s.Server Started @1042128ms
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60386}
[junit4] 2> 1041537 ERROR (jetty-launcher-8097-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@34f9e6c4{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:34579}
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.e.j.s.Server Started @1042128ms
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34579}
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-06-06T08:29:05.774Z
[junit4] 2> 1041537 ERROR (jetty-launcher-8097-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.2.0
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1041537 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-06-06T08:29:05.774Z
[junit4] 2> 1041538 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1041538 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1041540 INFO (zkConnectionManagerCallback-8101-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1041540 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1041540 INFO (zkConnectionManagerCallback-8099-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1041540 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1041541 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1041541 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1042140 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1042141 WARN (jetty-launcher-8097-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5f121244[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1042142 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1042143 WARN (jetty-launcher-8097-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@23d0c66a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1042144 WARN (jetty-launcher-8097-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5514330c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1042145 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60714/solr
[junit4] 2> 1042145 WARN (jetty-launcher-8097-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5e2fb134[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1042145 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1042146 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60714/solr
[junit4] 2> 1042147 INFO (zkConnectionManagerCallback-8112-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1042147 INFO (jetty-launcher-8097-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1042147 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1042149 INFO (zkConnectionManagerCallback-8115-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1042149 INFO (jetty-launcher-8097-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1042250 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1042252 INFO (zkConnectionManagerCallback-8117-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1042252 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1042356 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1042358 INFO (zkConnectionManagerCallback-8119-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1042358 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1042513 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34579_solr
[junit4] 2> 1042515 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.c.Overseer Overseer (id=72137205221818376-127.0.0.1:34579_solr-n_0000000000) starting
[junit4] 2> 1042525 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1042527 INFO (zkConnectionManagerCallback-8128-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1042527 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1042532 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60714/solr ready
[junit4] 2> 1042534 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34579_solr
[junit4] 2> 1042535 INFO (OverseerStateUpdate-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:34579_solr
[junit4] 2> 1042537 INFO (OverseerStateUpdate-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1042538 INFO (zkCallback-8127-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1042561 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1042569 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1042575 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:60386_solr as DOWN
[junit4] 2> 1042576 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1042576 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60386_solr
[junit4] 2> 1042578 INFO (zkCallback-8118-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1042578 INFO (zkCallback-8127-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1042578 INFO (zkCallback-8116-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1042580 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1042581 INFO (zkConnectionManagerCallback-8134-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1042581 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1042584 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1042586 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60714/solr ready
[junit4] 2> 1042587 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34579.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b3ec912
[junit4] 2> 1042593 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34579.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b3ec912
[junit4] 2> 1042593 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34579.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b3ec912
[junit4] 2> 1042594 INFO (jetty-launcher-8097-thread-2) [n:127.0.0.1:34579_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.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node1/.
[junit4] 2> 1042603 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1042618 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60386.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b3ec912
[junit4] 2> 1042624 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60386.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b3ec912
[junit4] 2> 1042624 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60386.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b3ec912
[junit4] 2> 1042625 INFO (jetty-launcher-8097-thread-1) [n:127.0.0.1:60386_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.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node2/.
[junit4] 2> 1042649 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1042651 INFO (zkConnectionManagerCallback-8140-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1042651 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1042653 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1042655 INFO (SUITE-AliasIntegrationTest-seed#[56400A8D0B28D0A7]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60714/solr ready
[junit4] 2> 1042685 INFO (TEST-AliasIntegrationTest.testModifyPropertiesV2-seed#[56400A8D0B28D0A7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testModifyPropertiesV2
[junit4] 2> 1042687 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1042692 INFO (OverseerThreadFactory-6633-thread-1-processing-n:127.0.0.1:34579_solr) [n:127.0.0.1:34579_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1meta
[junit4] 2> 1042808 INFO (OverseerStateUpdate-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1meta_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60386/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1042815 INFO (OverseerStateUpdate-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1meta_shard2_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:34579/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1043020 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr x:collection1meta_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1043020 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1meta_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1043020 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n3 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1044036 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 1044036 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 1044045 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1meta_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1044045 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.s.IndexSchema [collection1meta_shard2_replica_n3] Schema name=minimal
[junit4] 2> 1044047 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1044047 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard1_replica_n1' using configuration from collection collection1meta, trusted=true
[junit4] 2> 1044047 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1044047 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard2_replica_n3' using configuration from collection collection1meta, trusted=true
[junit4] 2> 1044047 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60386.solr.core.collection1meta.shard1.replica_n1' (registry 'solr.core.collection1meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b3ec912
[junit4] 2> 1044047 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1meta_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node2/collection1meta_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node2/./collection1meta_shard1_replica_n1/data/]
[junit4] 2> 1044048 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34579.solr.core.collection1meta.shard2.replica_n3' (registry 'solr.core.collection1meta.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b3ec912
[junit4] 2> 1044048 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.SolrCore [[collection1meta_shard2_replica_n3] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node1/collection1meta_shard2_replica_n3], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node1/./collection1meta_shard2_replica_n3/data/]
[junit4] 2> 1044091 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1044091 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1044092 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1044092 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1044123 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1044123 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1044124 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1044124 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1044125 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b15d920[collection1meta_shard1_replica_n1] main]
[junit4] 2> 1044125 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f586a6b[collection1meta_shard2_replica_n3] main]
[junit4] 2> 1044126 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1044127 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1044127 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1044127 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1044128 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1044128 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1635579066701578240
[junit4] 2> 1044129 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1044130 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1635579066703675392
[junit4] 2> 1044130 INFO (searcherExecutor-6643-thread-1-processing-n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n3 c:collection1meta s:shard2 r:core_node4) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.SolrCore [collection1meta_shard2_replica_n3] Registered new searcher Searcher@3f586a6b[collection1meta_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1044133 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 1044134 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard2
[junit4] 2> 1044135 INFO (searcherExecutor-6642-thread-1-processing-n:127.0.0.1:60386_solr x:collection1meta_shard1_replica_n1 c:collection1meta s:shard1 r:core_node2) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] Registered new searcher Searcher@4b15d920[collection1meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1044143 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1044143 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1044143 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34579/solr/collection1meta_shard2_replica_n3/
[junit4] 2> 1044144 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1044144 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.SyncStrategy http://127.0.0.1:34579/solr/collection1meta_shard2_replica_n3/ has no replicas
[junit4] 2> 1044144 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard2/leader after winning as /collections/collection1meta/leader_elect/shard2/election/72137205221818376-core_node4-n_0000000000
[junit4] 2> 1044144 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1044145 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard1
[junit4] 2> 1044148 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:34579/solr/collection1meta_shard2_replica_n3/ shard2
[junit4] 2> 1044151 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1044151 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1044151 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60386/solr/collection1meta_shard1_replica_n1/
[junit4] 2> 1044152 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1044152 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:60386/solr/collection1meta_shard1_replica_n1/ has no replicas
[junit4] 2> 1044152 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard1/leader after winning as /collections/collection1meta/leader_elect/shard1/election/72137205221818375-core_node2-n_0000000000
[junit4] 2> 1044154 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60386/solr/collection1meta_shard1_replica_n1/ shard1
[junit4] 2> 1044257 INFO (zkCallback-8118-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1044257 INFO (zkCallback-8118-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1044258 INFO (zkCallback-8116-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1044258 INFO (zkCallback-8116-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1044259 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1044260 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1044262 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node2 x:collection1meta_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1242
[junit4] 2> 1044264 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n3 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1meta_shard2_replica_n3&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1245
[junit4] 2> 1044267 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1044366 INFO (zkCallback-8116-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1044366 INFO (zkCallback-8116-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1044366 INFO (zkCallback-8118-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1044366 INFO (zkCallback-8118-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1044366 INFO (zkCallback-8116-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1044366 INFO (zkCallback-8118-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1044368 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=1681
[junit4] 2> 1044369 INFO (qtp668426116-22845) [n:127.0.0.1:34579_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection2meta&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1044394 INFO (OverseerThreadFactory-6633-thread-2-processing-n:127.0.0.1:34579_solr) [n:127.0.0.1:34579_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection2meta
[junit4] 2> 1044394 INFO (OverseerCollectionConfigSetProcessor-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1044610 INFO (OverseerStateUpdate-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection2meta",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection2meta_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60386/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1044813 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr x:collection2meta_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection2meta_shard1_replica_n1&action=CREATE&numShards=1&collection=collection2meta&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1045839 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 1045846 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection2meta_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1045848 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1045848 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection2meta_shard1_replica_n1' using configuration from collection collection2meta, trusted=true
[junit4] 2> 1045848 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60386.solr.core.collection2meta.shard1.replica_n1' (registry 'solr.core.collection2meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b3ec912
[junit4] 2> 1045848 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection2meta_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node2/collection2meta_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node2/./collection2meta_shard1_replica_n1/data/]
[junit4] 2> 1045887 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1045887 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1045888 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1045888 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1045889 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@26282df9[collection2meta_shard1_replica_n1] main]
[junit4] 2> 1045891 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1045892 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1045892 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1045892 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1635579068551266304
[junit4] 2> 1045895 INFO (searcherExecutor-6652-thread-1-processing-n:127.0.0.1:60386_solr x:collection2meta_shard1_replica_n1 c:collection2meta s:shard1 r:core_node2) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection2meta_shard1_replica_n1] Registered new searcher Searcher@26282df9[collection2meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1045901 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection2meta/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1045901 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2meta/leaders/shard1
[junit4] 2> 1045912 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1045912 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1045912 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60386/solr/collection2meta_shard1_replica_n1/
[junit4] 2> 1045913 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1045914 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:60386/solr/collection2meta_shard1_replica_n1/ has no replicas
[junit4] 2> 1045914 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2meta/leaders/shard1/leader after winning as /collections/collection2meta/leader_elect/shard1/election/72137205221818375-core_node2-n_0000000000
[junit4] 2> 1045917 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60386/solr/collection2meta_shard1_replica_n1/ shard1
[junit4] 2> 1046021 INFO (zkCallback-8116-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046021 INFO (zkCallback-8116-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046023 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1046026 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection2meta_shard1_replica_n1&action=CREATE&numShards=1&collection=collection2meta&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1213
[junit4] 2> 1046029 INFO (qtp668426116-22845) [n:127.0.0.1:34579_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1046127 INFO (zkCallback-8116-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046127 INFO (zkCallback-8118-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046128 INFO (zkCallback-8116-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046128 INFO (zkCallback-8116-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046128 INFO (qtp668426116-22845) [n:127.0.0.1:34579_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection2meta&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1760
[junit4] 2> 1046131 INFO (TEST-AliasIntegrationTest.testModifyPropertiesV2-seed#[56400A8D0B28D0A7]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 1046132 INFO (watches-8137-thread-1) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 1046132 INFO (TEST-AliasIntegrationTest.testModifyPropertiesV2-seed#[56400A8D0B28D0A7]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 1046145 INFO (OverseerCollectionConfigSetProcessor-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1046149 INFO (qtp663616204-22841) [n:127.0.0.1:60386_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/c params={} status=0 QTime=9
[junit4] 2> 1046150 INFO (TEST-AliasIntegrationTest.testModifyPropertiesV2-seed#[56400A8D0B28D0A7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testModifyPropertiesV2
[junit4] 2> 1046152 INFO (TEST-AliasIntegrationTest.testModifyPropertiesV2-seed#[56400A8D0B28D0A7]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1046156 INFO (qtp668426116-22851) [n:127.0.0.1:34579_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=collection2meta&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1046160 INFO (OverseerCollectionConfigSetProcessor-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1046162 INFO (OverseerThreadFactory-6633-thread-4-processing-n:127.0.0.1:34579_solr) [n:127.0.0.1:34579_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 1046163 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection2meta.shard1.replica_n1, tag=null
[junit4] 2> 1046163 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@21ce0d05: rootName = solr_60386, domain = solr.core.collection2meta.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection2meta.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@388ed068
[junit4] 2> 1046171 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr x:collection2meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection2meta_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@3ea823ca
[junit4] 2> 1046171 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection2meta.shard1.replica_n1, tag=3ea823ca
[junit4] 2> 1046171 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection2meta.shard1.leader, tag=3ea823ca
[junit4] 2> 1046173 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr x:collection2meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection2meta/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 1046175 INFO (qtp663616204-22839) [n:127.0.0.1:60386_solr x:collection2meta_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection2meta_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 1046282 INFO (zkCallback-8118-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046282 INFO (zkCallback-8116-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046282 INFO (zkCallback-8116-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046282 INFO (zkCallback-8118-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046282 INFO (zkCallback-8116-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046297 INFO (qtp668426116-22851) [n:127.0.0.1:34579_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=collection2meta&action=DELETE&wt=javabin&version=2} status=0 QTime=141
[junit4] 2> 1046298 INFO (qtp668426116-22848) [n:127.0.0.1:34579_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=collection1meta&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1046303 INFO (OverseerCollectionConfigSetProcessor-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1046303 INFO (OverseerThreadFactory-6633-thread-5-processing-n:127.0.0.1:34579_solr) [n:127.0.0.1:34579_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 1046304 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard1.replica_n1, tag=null
[junit4] 2> 1046304 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3aec44ff: rootName = solr_60386, domain = solr.core.collection1meta.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection1meta.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@10a7cfe3
[junit4] 2> 1046304 INFO (qtp668426116-22847) [n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n3 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard2.replica_n3, tag=null
[junit4] 2> 1046304 INFO (qtp668426116-22847) [n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n3 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@275fe3de: rootName = solr_34579, domain = solr.core.collection1meta.shard2.replica_n3, service url = null, agent id = null] for registry solr.core.collection1meta.shard2.replica_n3 / com.codahale.metrics.MetricRegistry@4815f572
[junit4] 2> 1046323 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@54fde09
[junit4] 2> 1046323 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard1.replica_n1, tag=54fde09
[junit4] 2> 1046323 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1meta.shard1.leader, tag=54fde09
[junit4] 2> 1046323 INFO (qtp668426116-22847) [n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n3 ] o.a.s.c.SolrCore [collection1meta_shard2_replica_n3] CLOSING SolrCore org.apache.solr.core.SolrCore@18eb7105
[junit4] 2> 1046323 INFO (qtp668426116-22847) [n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n3 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard2.replica_n3, tag=18eb7105
[junit4] 2> 1046323 INFO (qtp668426116-22847) [n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n3 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1meta.shard2.leader, tag=18eb7105
[junit4] 2> 1046326 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 1046326 INFO (qtp668426116-22847) [n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={}, version=1}
[junit4] 2> 1046328 INFO (qtp663616204-22842) [n:127.0.0.1:60386_solr x:collection1meta_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection1meta_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=24
[junit4] 2> 1046329 INFO (qtp668426116-22847) [n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n3 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection1meta_shard2_replica_n3&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=24
[junit4] 2> 1046438 INFO (zkCallback-8116-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046438 INFO (zkCallback-8116-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046438 INFO (zkCallback-8118-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046438 INFO (zkCallback-8116-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046438 INFO (zkCallback-8118-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046438 INFO (zkCallback-8118-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1046462 INFO (qtp668426116-22848) [n:127.0.0.1:34579_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=collection1meta&action=DELETE&wt=javabin&version=2} status=0 QTime=164
[junit4] 2> 1046489 INFO (TEST-AliasIntegrationTest.testModifyPropertiesV1-seed#[56400A8D0B28D0A7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testModifyPropertiesV1
[junit4] 2> 1046495 INFO (qtp663616204-22841) [n:127.0.0.1:60386_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1046496 INFO (qtp663616204-22841) [n:127.0.0.1:60386_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> 1046497 INFO (qtp668426116-22850) [n:127.0.0.1:34579_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1046502 INFO (OverseerThreadFactory-6633-thread-5-processing-n:127.0.0.1:34579_solr) [n:127.0.0.1:34579_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1meta
[junit4] 2> 1046503 INFO (OverseerCollectionConfigSetProcessor-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1046719 INFO (OverseerStateUpdate-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1meta_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60386/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1046723 INFO (OverseerStateUpdate-72137205221818376-127.0.0.1:34579_solr-n_0000000000) [n:127.0.0.1:34579_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1meta_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:34579/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1046926 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1meta_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1046926 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr x:collection1meta_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1047942 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 1047942 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 1047951 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1meta_shard1_replica_n1] Schema name=minimal
[junit4] 2> 1047951 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.IndexSchema [collection1meta_shard2_replica_n2] Schema name=minimal
[junit4] 2> 1047953 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1047953 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard2_replica_n2' using configuration from collection collection1meta, trusted=true
[junit4] 2> 1047954 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34579.solr.core.collection1meta.shard2.replica_n2' (registry 'solr.core.collection1meta.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b3ec912
[junit4] 2> 1047954 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1meta_shard2_replica_n2] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node1/collection1meta_shard2_replica_n2], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node1/./collection1meta_shard2_replica_n2/data/]
[junit4] 2> 1047956 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1047956 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard1_replica_n1' using configuration from collection collection1meta, trusted=true
[junit4] 2> 1047956 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60386.solr.core.collection1meta.shard1.replica_n1' (registry 'solr.core.collection1meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b3ec912
[junit4] 2> 1047956 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1meta_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node2/collection1meta_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_56400A8D0B28D0A7-001/tempDir-001/node2/./collection1meta_shard1_replica_n1/data/]
[junit4] 2> 1048004 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1048005 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1048005 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1048005 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1048006 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1048006 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1048006 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1048006 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1048006 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ea3ade7[collection1meta_shard2_replica_n2] main]
[junit4] 2> 1048006 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7361e5cf[collection1meta_shard1_replica_n1] main]
[junit4] 2> 1048009 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1048009 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1048009 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1048009 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1048010 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1048010 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1048010 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1635579070772150272
[junit4] 2> 1048010 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1635579070772150272
[junit4] 2> 1048013 INFO (searcherExecutor-6658-thread-1-processing-n:127.0.0.1:60386_solr x:collection1meta_shard1_replica_n1 c:collection1meta s:shard1 r:core_node3) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] Registered new searcher Searcher@7361e5cf[collection1meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1048018 INFO (searcherExecutor-6657-thread-1-processing-n:127.0.0.1:34579_solr x:collection1meta_shard2_replica_n2 c:collection1meta s:shard2 r:core_node4) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1meta_shard2_replica_n2] Registered new searcher Searcher@6ea3ade7[collection1meta_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1048022 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 1048023 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard2
[junit4] 2> 1048024 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 1048024 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard1
[junit4] 2> 1048030 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1048030 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1048030 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34579/solr/collection1meta_shard2_replica_n2/
[junit4] 2> 1048030 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1048031 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1048031 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1048031 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60386/solr/collection1meta_shard1_replica_n1/
[junit4] 2> 1048031 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:34579/solr/collection1meta_shard2_replica_n2/ has no replicas
[junit4] 2> 1048031 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard2/leader after winning as /collections/collection1meta/leader_elect/shard2/election/72137205221818376-core_node4-n_0000000000
[junit4] 2> 1048031 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1048032 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:60386/solr/collection1meta_shard1_replica_n1/ has no replicas
[junit4] 2> 1048032 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard1/leader after winning as /collections/collection1meta/leader_elect/shard1/election/72137205221818375-core_node3-n_0000000000
[junit4] 2> 1048035 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:34579/solr/collection1meta_shard2_replica_n2/ shard2
[junit4] 2> 1048036 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60386/solr/collection1meta_shard1_replica_n1/ shard1
[junit4] 2> 1048140 INFO (zkCallback-8118-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1048140 INFO (zkCallback-8116-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1048140 INFO (zkCallback-8116-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1048140 INFO (zkCallback-8118-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1048141 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1048142 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1048146 INFO (qtp663616204-22836) [n:127.0.0.1:60386_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1221
[junit4] 2> 1048146 INFO (qtp668426116-22849) [n:127.0.0.1:34579_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_repl
[...truncated too long message...]
tegrationTest.testErrorChecks
[junit4] OK 4.59s J1 | AliasIntegrationTest.testProperties
[junit4] OK 5.23s J1 | AliasIntegrationTest.testClusterStateProviderAPI
[junit4] OK 10.3s J1 | AliasIntegrationTest.testDeleteAliasWithExistingCollectionName
[junit4] Completed [1/5] on J1 in 66.70s, 9 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
[junit4] OK 6.47s J0 | AliasIntegrationTest.testModifyPropertiesV2
[junit4] OK 4.46s J0 | AliasIntegrationTest.testModifyPropertiesV1
[junit4] OK 11.7s J0 | AliasIntegrationTest.testDeleteOneOfTwoCollectionsAliased
[junit4] OK 4.45s J0 | AliasIntegrationTest.testModifyPropertiesCAR
[junit4] OK 6.42s J0 | AliasIntegrationTest.test
[junit4] OK 2.57s J0 | AliasIntegrationTest.testErrorChecks
[junit4] OK 4.76s J0 | AliasIntegrationTest.testProperties
[junit4] OK 4.87s J0 | AliasIntegrationTest.testClusterStateProviderAPI
[junit4] OK 12.4s J0 | AliasIntegrationTest.testDeleteAliasWithExistingCollectionName
[junit4] Completed [2/5] on J0 in 68.31s, 9 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
[junit4] OK 5.05s J1 | AliasIntegrationTest.testModifyPropertiesV2
[junit4] OK 4.84s J1 | AliasIntegrationTest.testModifyPropertiesV1
[junit4] OK 11.0s J1 | AliasIntegrationTest.testDeleteOneOfTwoCollectionsAliased
[junit4] OK 4.36s J1 | AliasIntegrationTest.testModifyPropertiesCAR
[junit4] OK 6.24s J1 | AliasIntegrationTest.test
[junit4] OK 2.59s J1 | AliasIntegrationTest.testErrorChecks
[junit4] OK 4.33s J1 | AliasIntegrationTest.testProperties
[junit4] OK 4.06s J1 | AliasIntegrationTest.testClusterStateProviderAPI
[junit4] OK 11.2s J1 | AliasIntegrationTest.testDeleteAliasWithExistingCollectionName
[junit4] Completed [3/5] on J1 in 58.09s, 9 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
[junit4] OK 4.94s J0 | AliasIntegrationTest.testModifyPropertiesV2
[junit4] OK 5.12s J0 | AliasIntegrationTest.testModifyPropertiesV1
[junit4] OK 9.57s J0 | AliasIntegrationTest.testDeleteOneOfTwoCollectionsAliased
[junit4] OK 4.34s J0 | AliasIntegrationTest.testModifyPropertiesCAR
[junit4] OK 6.23s J0 | AliasIntegrationTest.test
[junit4] OK 2.40s J0 | AliasIntegrationTest.testErrorChecks
[junit4] OK 4.26s J0 | AliasIntegrationTest.testProperties
[junit4] OK 4.13s J0 | AliasIntegrationTest.testClusterStateProviderAPI
[junit4] OK 11.1s J0 | AliasIntegrationTest.testDeleteAliasWithExistingCollectionName
[junit4] Completed [4/5] on J0 in 59.94s, 9 tests
[junit4]
[junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
[junit4] OK 4.03s J1 | AliasIntegrationTest.testModifyPropertiesV2
[junit4] OK 4.18s J1 | AliasIntegrationTest.testModifyPropertiesV1
[junit4] OK 10.1s J1 | AliasIntegrationTest.testDeleteOneOfTwoCollectionsAliased
[junit4] OK 4.14s J1 | AliasIntegrationTest.testModifyPropertiesCAR
[junit4] OK 5.82s J1 | AliasIntegrationTest.test
[junit4] OK 2.36s J1 | AliasIntegrationTest.testErrorChecks
[junit4] OK 4.11s J1 | AliasIntegrationTest.testProperties
[junit4] OK 4.02s J1 | AliasIntegrationTest.testClusterStateProviderAPI
[junit4] OK 11.2s J1 | AliasIntegrationTest.testDeleteAliasWithExistingCollectionName
[junit4] Completed [5/5] on J1 in 52.66s, 9 tests
[junit4]
[junit4] JVM J0: 0.73 .. 130.13 = 129.40s
[junit4] JVM J1: 0.72 .. 180.11 = 179.39s
[junit4] Execution time total: 3 minutes
[junit4] Tests summary: 5 suites, 45 tests
[junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J1/temp])
[junit4] Could not remove temporary path: /export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris/solr/build/solr-core/test/J0/temp])
[echo] 5 slowest tests:
[junit4:tophints] 287.76s | org.apache.solr.search.facet.TestJsonFacets
[junit4:tophints] 280.66s | org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4:tophints] 198.89s | org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4:tophints] 136.97s | org.apache.solr.update.processor.CategoryRoutedAliasUpdateProcessorTest
[junit4:tophints] 133.15s | org.apache.solr.cloud.CollectionsAPISolrJTest
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 3 minutes 3 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.AliasIntegrationTest
[repro] Exiting with code 0
+ 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
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2805)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2684)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2665)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3052)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to Solaris VBOX
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1743)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:957)
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2663)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:248)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:79)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:741)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1843)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3054)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath.act(FilePath.java:1074)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2663)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:248)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:79)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:741)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1843)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error?
[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=ca07f444c85da5dc45cd794c0410a90be251d29c, workspace=/export/home/jenkins/workspace/Lucene-Solr-8.x-Solaris
[WARNINGS] Computing warning deltas based on reference build #160
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