This looks like a real concurrency bug somewhere in the test. On Mon, Sep 28, 2020 at 8:39 AM Apache Jenkins Server <jenk...@builds.apache.org> wrote: > > Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/437/ > > 1 tests failed. > FAILED: org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeAdded > > Error Message: > > > Stack Trace: > java.util.ConcurrentModificationException > at > __randomizedtesting.SeedInfo.seed([E4460E44D1340265:818558337397AA66]:0) > at java.util.ArrayList$Itr.checkForComodification(ArrayList.java:909) > at java.util.ArrayList$Itr.next(ArrayList.java:859) > at java.util.AbstractCollection.toString(AbstractCollection.java:461) > at java.lang.String.valueOf(String.java:2994) > at java.lang.StringBuilder.append(StringBuilder.java:131) > at java.util.AbstractMap.toString(AbstractMap.java:559) > at java.lang.String.valueOf(String.java:2994) > at java.lang.StringBuilder.append(StringBuilder.java:131) > at > org.apache.solr.cloud.autoscaling.ComputePlanActionTest.testNodeAdded(ComputePlanActionTest.java:402) > 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 15148 lines...] > [junit4] Suite: org.apache.solr.cloud.autoscaling.ComputePlanActionTest > [junit4] 2> 1593780 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to > test-framework derived value of > '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf' > [junit4] 2> 1593781 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.SolrTestCaseJ4 Created dataDir: > /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E4460E44D1340265-001/data-dir-131-001 > [junit4] 2> 1593781 WARN > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=11 numCloses=11 > [junit4] 2> 1593781 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) > w/NUMERIC_DOCVALUES_SYSPROP=false > [junit4] 2> 1593783 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: > @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, > clientAuth=NaN) > [junit4] 2> 1593783 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: > test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom > [junit4] 2> 1593784 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in > /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E4460E44D1340265-001/tempDir-001 > [junit4] 2> 1593784 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.ZkTestServer STARTING ZK TEST SERVER > [junit4] 2> 1593784 INFO (ZkTestServer Run Thread) [ ] > o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0 > [junit4] 2> 1593784 INFO (ZkTestServer Run Thread) [ ] > o.a.s.c.ZkTestServer Starting server > [junit4] 2> 1593785 WARN (ZkTestServer Run Thread) [ ] > o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0. > [junit4] 2> 1593884 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.ZkTestServer start zk server on port: 36511 > [junit4] 2> 1593884 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:36511 > [junit4] 2> 1593884 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36511 > [junit4] 2> 1593884 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.ZkTestServer connecting to 127.0.0.1 36511 > [junit4] 2> 1593886 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 1593891 INFO (zkConnectionManagerCallback-14772-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 1593891 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 1593903 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 1593905 INFO (zkConnectionManagerCallback-14774-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 1593905 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 1593912 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 1593913 INFO (zkConnectionManagerCallback-14776-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 1593913 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 1594017 WARN (jetty-launcher-14777-thread-1) [ ] > o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, > break even is size 23 > [junit4] 2> 1594017 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) > [junit4] 2> 1594017 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... > [junit4] 2> 1594017 INFO (jetty-launcher-14777-thread-1) [ ] > o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: > a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_252-b09 > [junit4] 2> 1594023 INFO (jetty-launcher-14777-thread-1) [ ] > o.e.j.s.session DefaultSessionIdManager workerName=node0 > [junit4] 2> 1594023 INFO (jetty-launcher-14777-thread-1) [ ] > o.e.j.s.session No SessionScavenger set, using defaults > [junit4] 2> 1594023 INFO (jetty-launcher-14777-thread-1) [ ] > o.e.j.s.session node0 Scavenging every 660000ms > [junit4] 2> 1594024 INFO (jetty-launcher-14777-thread-1) [ ] > o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@4c5f7818{/solr,null,AVAILABLE} > [junit4] 2> 1594032 INFO (jetty-launcher-14777-thread-1) [ ] > o.e.j.s.AbstractConnector Started ServerConnector@44fa37d3{SSL, (ssl, > http/1.1)}{127.0.0.1:41269} > [junit4] 2> 1594032 INFO (jetty-launcher-14777-thread-1) [ ] > o.e.j.s.Server Started @1594069ms > [junit4] 2> 1594032 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, > hostPort=41269} > [junit4] 2> 1594032 ERROR (jetty-launcher-14777-thread-1) [ ] > o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be > missing or incomplete. > [junit4] 2> 1594032 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.s.SolrDispatchFilter Using logger factory > org.apache.logging.slf4j.Log4jLoggerFactory > [junit4] 2> 1594032 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version > 8.7.0 > [junit4] 2> 1594033 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port > null > [junit4] 2> 1594033 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: > /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr > [junit4] 2> 1594033 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: > 2020-09-28T06:03:18.134Z > [junit4] 2> 1594038 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 1594042 INFO (zkConnectionManagerCallback-14779-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 1594043 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 1594045 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... > [junit4] 2> 1595022 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: > WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] > [junit4] 2> 1595022 WARN (jetty-launcher-14777-thread-1) [ ] > o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport > since Java 8 or lower versions does not support SSL + HTTP/2 > [junit4] 2> 1595023 WARN (jetty-launcher-14777-thread-1) [ ] > o.e.j.u.s.S.config Trusting all certificates configured for > Client@7a564bba[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 1595023 WARN (jetty-launcher-14777-thread-1) [ ] > o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for > Client@7a564bba[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 1595025 WARN (jetty-launcher-14777-thread-1) [ ] > o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport > since Java 8 or lower versions does not support SSL + HTTP/2 > [junit4] 2> 1595026 WARN (jetty-launcher-14777-thread-1) [ ] > o.e.j.u.s.S.config Trusting all certificates configured for > Client@1d1bb395[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 1595026 WARN (jetty-launcher-14777-thread-1) [ ] > o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for > Client@1d1bb395[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 1595027 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36511/solr > [junit4] 2> 1595027 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 1595028 INFO (zkConnectionManagerCallback-14789-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 1595028 INFO (jetty-launcher-14777-thread-1) [ ] > o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 1595130 INFO (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.c.c.ConnectionManager Waiting for client > to connect to ZooKeeper > [junit4] 2> 1595131 INFO (zkConnectionManagerCallback-14791-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 1595131 INFO (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.c.c.ConnectionManager Client is connected > to ZooKeeper > [junit4] 2> 1595194 INFO (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.c.OverseerElectionContext I am going to > be the leader 127.0.0.1:41269_solr > [junit4] 2> 1595195 INFO (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer Overseer > (id=72209578990960645-127.0.0.1:41269_solr-n_0000000000) starting > [junit4] 2> 1595199 INFO > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer Starting to work on the main > queue : 127.0.0.1:41269_solr > [junit4] 2> 1595199 INFO (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.c.ZkController Register node as live in > ZooKeeper:/live_nodes/127.0.0.1:41269_solr > [junit4] 2> 1595199 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and > .scheduled_maintenance triggers > [junit4] 2> 1595200 INFO > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from > ZooKeeper... (0) -> (1) > [junit4] 2> 1595200 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with > znode version 1 > [junit4] 2> 1595201 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, > lastZnodeVersion 0 > [junit4] 2> 1595201 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto > znodeVersion 1 > [junit4] 2> 1595201 INFO (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.p.PackageLoader /packages.json updated to > version -1 > [junit4] 2> 1595202 WARN (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.c.CoreContainer Not all security plugins > configured! authentication=disabled authorization=disabled. Solr is only as > secure as you make it. Consider configuring authentication/authorization > before exposing Solr to users internal or external. See > https://s.apache.org/solrsecurity for more info > [junit4] 2> 1595211 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - > Initial livenodes: [127.0.0.1:41269_solr] > [junit4] 2> 1595212 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / > nodeAdded markers > [junit4] 2> 1595212 DEBUG (ScheduledTrigger-14796-thread-1) [ ] > o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with > currently live nodes: 1 and last live nodes: 1 > [junit4] 2> 1595212 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, > lastZnodeVersion 1 > [junit4] 2> 1595213 INFO (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.h.a.MetricsHistoryHandler No .system > collection, keeping metrics history in memory. > [junit4] 2> 1595240 INFO (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_41269.solr.node' (registry 'solr.node') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@4e6224d8 > [junit4] 2> 1595248 INFO (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_41269.solr.jvm' (registry 'solr.jvm') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@4e6224d8 > [junit4] 2> 1595248 INFO (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_41269.solr.jetty' (registry 'solr.jetty') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@4e6224d8 > [junit4] 2> 1595249 INFO (jetty-launcher-14777-thread-1) > [n:127.0.0.1:41269_solr ] o.a.s.c.CorePropertiesLocator Found 0 core > definitions underneath > /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E4460E44D1340265-001/tempDir-001/node1 > [junit4] 2> 1595258 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1 > [junit4] 2> 1595259 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper > [junit4] 2> 1595260 INFO (zkConnectionManagerCallback-14808-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 1595260 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 1595267 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) > [junit4] 2> 1595267 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36511/solr ready > [junit4] 2> 1595281 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.SolrTestCaseJ4 ###Starting > testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard > [junit4] 2> 1595282 DEBUG (zkCallback-14790-thread-1) [ ] > o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode > version 2 > [junit4] 2> 1595282 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto > znodeVersion 2 > [junit4] 2> 1595284 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> > (1) > [junit4] 2> 1595284 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / > nodeAdded markers > [junit4] 2> 1595285 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, > lastZnodeVersion 2 > [junit4] 2> 1595325 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for > 2147483647 transient cores > [junit4] 2> 1595326 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} > status=0 QTime=0 > [junit4] 2> 1595326 DEBUG (qtp184603384-24880) [n:127.0.0.1:41269_solr > ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration > [junit4] 2> 1595326 INFO (qtp184603384-24880) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling > params={wt=javabin&version=2} status=0 QTime=4 > [junit4] 2> 1595327 DEBUG (zkCallback-14790-thread-1) [ ] > o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode > version 3 > [junit4] 2> 1595327 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto > znodeVersion 3 > [junit4] 2> 1595327 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / > nodeAdded markers > [junit4] 2> 1595327 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, > lastZnodeVersion 3 > [junit4] 2> 1595332 INFO (qtp184603384-24878) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} > status=0 QTime=0 > [junit4] 2> 1595332 DEBUG (qtp184603384-24879) [n:127.0.0.1:41269_solr > ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration > [junit4] 2> 1595332 INFO (qtp184603384-24879) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling > params={wt=javabin&version=2} status=0 QTime=2 > [junit4] 2> 1595333 DEBUG (zkCallback-14790-thread-1) [ ] > o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode > version 4 > [junit4] 2> 1595333 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto > znodeVersion 4 > [junit4] 2> 1595333 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / > nodeAdded markers > [junit4] 2> 1595333 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, > lastZnodeVersion 4 > [junit4] 2> 1595337 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} > status=0 QTime=0 > [junit4] 2> 1595337 DEBUG (qtp184603384-24876) [n:127.0.0.1:41269_solr > ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration > [junit4] 2> 1595338 INFO (qtp184603384-24876) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling > params={wt=javabin&version=2} status=0 QTime=2 > [junit4] 2> 1595338 DEBUG (zkCallback-14790-thread-1) [ ] > o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode > version 5 > [junit4] 2> 1595338 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto > znodeVersion 5 > [junit4] 2> 1595340 INFO (qtp184603384-24878) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} > status=0 QTime=0 > [junit4] 2> 1595340 DEBUG (qtp184603384-24880) [n:127.0.0.1:41269_solr > ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration > [junit4] 2> 1595340 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - > Initial livenodes: [127.0.0.1:41269_solr] > [junit4] 2> 1595340 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger > instantiated with properties: {preferredOperation=addreplica, > event=nodeAdded, waitFor=1, actions=[{name=compute_plan, > class=solr.ComputePlanAction}, {name=test, > class=org.apache.solr.cloud.autoscaling.ComputePlanActionTest$AssertingTriggerAction}], > enabled=true} > [junit4] 2> 1595340 INFO (qtp184603384-24880) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling > params={wt=javabin&version=2} status=0 QTime=2 > [junit4] 2> 1595340 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / > nodeAdded markers > [junit4] 2> 1595341 DEBUG (zkCallback-14790-thread-1) [ ] > o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode > version 6 > [junit4] 2> 1595341 INFO (qtp184603384-24879) [n:127.0.0.1:41269_solr > ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with > params > collection.configName=conf&maxShardsPerNode=2&name=testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 > and sendToOCPQueue=true > [junit4] 2> 1595341 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger > [junit4] 2> 1595341 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 > [junit4] 2> 1595341 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, > lastZnodeVersion 5 > [junit4] 2> 1595342 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto > znodeVersion 6 > [junit4] 2> 1595342 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / > nodeAdded markers > [junit4] 2> 1595343 DEBUG > (OverseerAutoScalingTriggerThread-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, > lastZnodeVersion 6 > [junit4] 2> 1595344 INFO > (OverseerThreadFactory-14798-thread-1-processing-n:127.0.0.1:41269_solr) > [n:127.0.0.1:41269_solr ] o.a.s.c.a.c.CreateCollectionCmd Create > collection testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 > [junit4] 2> 1595347 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, > message = { > [junit4] 2> > "name":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0", > [junit4] 2> "fromApi":"true", > [junit4] 2> "collection.configName":"conf", > [junit4] 2> "numShards":"1", > [junit4] 2> "maxShardsPerNode":"2", > [junit4] 2> "nrtReplicas":"1", > [junit4] 2> "stateFormat":"2", > [junit4] 2> "replicationFactor":"1", > [junit4] 2> "operation":"create"} current state version: 0 > [junit4] 2> 1595347 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ClusterStateMutator building a new > cName: testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 > [junit4] 2> 1595348 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ZkStateWriter going to > create_collection > /collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json > [junit4] 2> 1595451 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} > status=0 QTime=0 > [junit4] 2> 1595456 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, > message = { > [junit4] 2> "operation":"ADDREPLICA", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "state":"down", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "type":"NRT", > [junit4] 2> "waitForFinalState":"false"} current state version: 0 > [junit4] 2> 1595456 INFO > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.SliceMutator createReplica() { > [junit4] 2> "operation":"ADDREPLICA", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "state":"down", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "type":"NRT", > [junit4] 2> "waitForFinalState":"false"} > [junit4] 2> 1595457 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ > [junit4] 2> "range":"80000000-7fffffff", > [junit4] 2> "state":"active", > [junit4] 2> "replicas":{}} > [junit4] 2> 1595457 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ > [junit4] 2> "range":"80000000-7fffffff", > [junit4] 2> "state":"active", > [junit4] 2> "replicas":{"core_node2":{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "state":"down", > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "type":"NRT"}}} > [junit4] 2> 1595557 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ZkStateWriter going to > update_collection > /collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json > version: 0 > [junit4] 2> 1595704 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.h.a.CoreAdminOperation core create command > qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0&shard=shard1&wt=javabin&version=2&replicaType=NRT > [junit4] 2> 1595707 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, > message = { > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "core_node_name":"core_node2", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"down", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false", > [junit4] 2> "operation":"state"} current state version: 0 > [junit4] 2> 1595707 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ReplicaMutator Update state > numShards=1 message={ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "core_node_name":"core_node2", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"down", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false", > [junit4] 2> "operation":"state"} > [junit4] 2> 1595707 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ReplicaMutator Will update state for > replica: core_node2:{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "state":"down", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false"} > [junit4] 2> 1595707 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ReplicaMutator Collection is now: > DocCollection(testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0//collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json/1)={ > [junit4] 2> "pullReplicas":"0", > [junit4] 2> "replicationFactor":"1", > [junit4] 2> "router":{"name":"compositeId"}, > [junit4] 2> "maxShardsPerNode":"2", > [junit4] 2> "autoAddReplicas":"false", > [junit4] 2> "nrtReplicas":"1", > [junit4] 2> "tlogReplicas":"0", > [junit4] 2> "shards":{"shard1":{ > [junit4] 2> "range":"80000000-7fffffff", > [junit4] 2> "state":"active", > [junit4] 2> "replicas":{"core_node2":{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "state":"down", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false"}}}}} > [junit4] 2> 1595713 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0 > [junit4] 2> 1595720 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.s.IndexSchema Schema name=minimal > [junit4] 2> 1595732 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id > [junit4] 2> 1595732 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.CoreContainer Creating SolrCore > 'testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1' > using configuration from configset conf, trusted=true > [junit4] 2> 1595734 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_41269.solr.core.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0.shard1.replica_n1' > (registry > 'solr.core.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0.shard1.replica_n1') > enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e6224d8 > [junit4] 2> 1595734 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.SolrCore > [[testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1] ] > Opening new SolrCore at > [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E4460E44D1340265-001/tempDir-001/node1/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1], > > dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E4460E44D1340265-001/tempDir-001/node1/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1/data/] > [junit4] 2> 1595779 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.u.UpdateHandler Using UpdateLog implementation: > org.apache.solr.update.UpdateLog > [junit4] 2> 1595779 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH > numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 > [junit4] 2> 1595780 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.u.CommitTracker Hard AutoCommit: disabled > [junit4] 2> 1595780 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.u.CommitTracker Soft AutoCommit: disabled > [junit4] 2> 1595782 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: > /configs/conf > [junit4] 2> 1595782 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using > ZooKeeperStorageIO:path=/configs/conf > [junit4] 2> 1595782 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms > [junit4] 2> 1595782 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.u.UpdateLog Could not find max version in index or recent updates, > using new clock 1679056437125316608 > [junit4] 2> 1595785 INFO > (searcherExecutor-14812-thread-1-processing-n:127.0.0.1:41269_solr > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.SolrCore > [testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1] > Registered new searcher autowarm time: 0 ms > [junit4] 2> 1595787 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.ZkShardTerms Successful update of terms at > /collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/terms/shard1 > to Terms{values={core_node2=0}, version=0} > [junit4] 2> 1595787 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.ShardLeaderElectionContextBase make sure parent is created > /collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/leaders/shard1 > [junit4] 2> 1595790 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. > [junit4] 2> 1595790 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync > [junit4] 2> 1595790 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.SyncStrategy Sync replicas to > https://127.0.0.1:41269/solr/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1/ > [junit4] 2> 1595790 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.SyncStrategy Sync Success - now sync replicas to me > [junit4] 2> 1595790 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.SyncStrategy > https://127.0.0.1:41269/solr/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1/ > has no replicas > [junit4] 2> 1595790 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node > /collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/leaders/shard1/leader > after winning as > /collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/leader_elect/shard1/election/72209578990960645-core_node2-n_0000000000 > [junit4] 2> 1595792 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.ShardLeaderElectionContext I am the new leader: > https://127.0.0.1:41269/solr/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1/ > shard1 > [junit4] 2> 1595792 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, > message = { > [junit4] 2> "operation":"leader", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "state":"active"} current state version: 0 > [junit4] 2> 1595893 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ZkStateWriter going to > update_collection > /collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json > version: 1 > [junit4] 2> 1595894 INFO (zkCallback-14790-thread-1) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json] > for collection [testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0] has > occurred - updating... (live nodes size: [1]) > [junit4] 2> 1595894 INFO (zkCallback-14790-thread-2) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json] > for collection [testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0] has > occurred - updating... (live nodes size: [1]) > [junit4] 2> 1595894 INFO (zkCallback-14790-thread-3) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json] > for collection [testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0] has > occurred - updating... (live nodes size: [1]) > [junit4] 2> 1595895 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0 s:shard1 > r:core_node2 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1 ] > o.a.s.c.ZkController I am the leader, no recovery necessary > [junit4] 2> 1595897 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores > params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1&action=CREATE&numShards=1&collection=testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0&shard=shard1&wt=javabin&version=2&replicaType=NRT} > status=0 QTime=192 > [junit4] 2> 1595897 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, > message = { > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "core_node_name":"core_node2", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"active", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false", > [junit4] 2> "operation":"state"} current state version: 0 > [junit4] 2> 1595897 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ReplicaMutator Update state > numShards=1 message={ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "core_node_name":"core_node2", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"active", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false", > [junit4] 2> "operation":"state"} > [junit4] 2> 1595897 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ReplicaMutator Will update state for > replica: core_node2:{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "state":"active", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false", > [junit4] 2> "leader":"true"} > [junit4] 2> 1595897 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ReplicaMutator Collection is now: > DocCollection(testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0//collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json/2)={ > [junit4] 2> "pullReplicas":"0", > [junit4] 2> "replicationFactor":"1", > [junit4] 2> "router":{"name":"compositeId"}, > [junit4] 2> "maxShardsPerNode":"2", > [junit4] 2> "autoAddReplicas":"false", > [junit4] 2> "nrtReplicas":"1", > [junit4] 2> "tlogReplicas":"0", > [junit4] 2> "shards":{"shard1":{ > [junit4] 2> "range":"80000000-7fffffff", > [junit4] 2> "state":"active", > [junit4] 2> "replicas":{"core_node2":{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0_shard1_replica_n1", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "state":"active", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false", > [junit4] 2> "leader":"true"}}}}} > [junit4] 2> 1595899 INFO (qtp184603384-24879) [n:127.0.0.1:41269_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> 1595998 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ZkStateWriter going to > update_collection > /collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json > version: 2 > [junit4] 2> 1595998 INFO (zkCallback-14790-thread-3) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json] > for collection [testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0] has > occurred - updating... (live nodes size: [1]) > [junit4] 2> 1595998 INFO (zkCallback-14790-thread-4) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json] > for collection [testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0] has > occurred - updating... (live nodes size: [1]) > [junit4] 2> 1595998 INFO (zkCallback-14790-thread-2) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json] > for collection [testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0] has > occurred - updating... (live nodes size: [1]) > [junit4] 2> 1595999 INFO (zkCallback-14790-thread-1) [ ] > o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent > state:SyncConnected type:NodeDataChanged > path:/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json] > for collection [testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0] has > occurred - updating... (live nodes size: [1]) > [junit4] 2> 1595999 INFO (qtp184603384-24879) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections > params={collection.configName=conf&maxShardsPerNode=2&name=testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} > status=0 QTime=658 > [junit4] 2> 1596000 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.SolrCloudTestCase waitForState > (testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0): Timed out waiting > for replicas of new collection to be active > [junit4] 2> 1596003 WARN > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short > content, break even is size 23 > [junit4] 2> 1596004 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding > port=0) > [junit4] 2> 1596004 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try > number 2 ... > [junit4] 2> 1596004 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: > 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm > 1.8.0_252-b09 > [junit4] 2> 1596006 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 > [junit4] 2> 1596006 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.s.session No SessionScavenger set, using defaults > [junit4] 2> 1596006 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.s.session node0 Scavenging every 600000ms > [junit4] 2> 1596007 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@24dc523c{/solr,null,AVAILABLE} > [junit4] 2> 1596007 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.s.AbstractConnector Started ServerConnector@538f7dc7{SSL, > (ssl, http/1.1)}{127.0.0.1:41057} > [junit4] 2> 1596007 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.s.Server Started @1596044ms > [junit4] 2> 1596007 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, > hostPort=41057} > [junit4] 2> 1596007 ERROR > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. > Logging may be missing or incomplete. > [junit4] 2> 1596007 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.s.SolrDispatchFilter Using logger factory > org.apache.logging.slf4j.Log4jLoggerFactory > [junit4] 2> 1596007 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ > version 8.7.0 > [junit4] 2> 1596008 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode > on port null > [junit4] 2> 1596008 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: > /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr > [junit4] 2> 1596008 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: > 2020-09-28T06:03:20.109Z > [junit4] 2> 1596011 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to > ZooKeeper > [junit4] 2> 1596011 INFO (zkConnectionManagerCallback-14818-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 1596011 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 1596012 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... > [junit4] 2> 1596342 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger > [junit4] 2> 1596343 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 > [junit4] 2> 1597343 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger > [junit4] 2> 1597343 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Found livenodes: 1 > [junit4] 2> 1597346 INFO > (OverseerCollectionConfigSetProcessor-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_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> 1597838 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: > WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] > [junit4] 2> 1597839 WARN > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 > transport since Java 8 or lower versions does not support SSL + HTTP/2 > [junit4] 2> 1597847 WARN > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.u.s.S.config Trusting all certificates configured for > Client@65cacdbe[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 1597847 WARN > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm > configured for Client@65cacdbe[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 1597850 WARN > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 > transport since Java 8 or lower versions does not support SSL + HTTP/2 > [junit4] 2> 1597850 WARN > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.u.s.S.config Trusting all certificates configured for > Client@1a0b0d9b[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 1597850 WARN > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm > configured for Client@1a0b0d9b[provider=null,keyStore=null,trustStore=null] > [junit4] 2> 1597852 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36511/solr > [junit4] 2> 1597852 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to > ZooKeeper > [junit4] 2> 1597854 INFO (zkConnectionManagerCallback-14828-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 1597855 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper > [junit4] 2> 1597957 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.c.c.ConnectionManager Waiting for client > to connect to ZooKeeper > [junit4] 2> 1597958 INFO (zkConnectionManagerCallback-14830-thread-1) [ > ] o.a.s.c.c.ConnectionManager zkClient has connected > [junit4] 2> 1597958 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.c.c.ConnectionManager Client is > connected to ZooKeeper > [junit4] 2> 1597962 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.c.c.ZkStateReader Updated live nodes > from ZooKeeper... (0) -> (1) > [junit4] 2> 1597966 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.c.ZkController Publish > node=127.0.0.1:41057_solr as DOWN > [junit4] 2> 1597967 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.c.TransientSolrCoreCacheDefault > Allocating transient cache for 2147483647 transient cores > [junit4] 2> 1597967 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.c.ZkController Register node as live in > ZooKeeper:/live_nodes/127.0.0.1:41057_solr > [junit4] 2> 1597967 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, > message = { > [junit4] 2> "operation":"downnode", > [junit4] 2> "node_name":"127.0.0.1:41057_solr"} current state version: > 0 > [junit4] 2> 1597967 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.NodeMutator DownNode state invoked > for node: 127.0.0.1:41057_solr > [junit4] 2> 1597968 INFO (zkCallback-14807-thread-2) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) > [junit4] 2> 1597968 INFO (zkCallback-14790-thread-5) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) > [junit4] 2> 1597968 INFO (zkCallback-14807-thread-1) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) > [junit4] 2> 1597968 INFO (zkCallback-14829-thread-1) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) > [junit4] 2> 1597970 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.p.PackageLoader /packages.json updated > to version -1 > [junit4] 2> 1597970 WARN > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.c.CoreContainer Not all security plugins > configured! authentication=disabled authorization=disabled. Solr is only as > secure as you make it. Consider configuring authentication/authorization > before exposing Solr to users internal or external. See > https://s.apache.org/solrsecurity for more info > [junit4] 2> 1597981 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.h.a.MetricsHistoryHandler No .system > collection, keeping metrics history in memory. > [junit4] 2> 1598003 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_41057.solr.node' (registry 'solr.node') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@4e6224d8 > [junit4] 2> 1598013 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_41057.solr.jvm' (registry 'solr.jvm') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@4e6224d8 > [junit4] 2> 1598013 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_41057.solr.jetty' (registry 'solr.jetty') enabled at server: > com.sun.jmx.mbeanserver.JmxMBeanServer@4e6224d8 > [junit4] 2> 1598014 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [n:127.0.0.1:41057_solr ] o.a.s.c.CorePropertiesLocator Found 0 core > definitions underneath > /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E4460E44D1340265-001/tempDir-001/node2 > [junit4] 2> 1598037 INFO > (TEST-ComputePlanActionTest.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard-seed#[E4460E44D1340265]) > [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2 > [junit4] 2> 1598343 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger > [junit4] 2> 1598343 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 > [junit4] 2> 1598343 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:41057_solr at time > 2319110896718434 > [junit4] 2> 1599344 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger > [junit4] 2> 1599344 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Found livenodes: 2 > [junit4] 2> 1599345 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing > registered processor for nodes: [127.0.0.1:41057_solr] added at times > [2319110896718434], now=2319111898154249 > [junit4] 2> 1599345 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: { > [junit4] 2> "id":"83d3815319262T3ndaag1h0zlskyji6jentgt3t", > [junit4] 2> "source":"node_added_trigger", > [junit4] 2> "eventTime":2319110896718434, > [junit4] 2> "eventType":"NODEADDED", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[2319110896718434], > [junit4] 2> "preferredOperation":"addreplica", > [junit4] 2> "nodeNames":["127.0.0.1:41057_solr"], > [junit4] 2> "replicaType":"NRT"}} > [junit4] 2> 1599345 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.ScheduledTriggers Pausing all triggers: [node_added_trigger] > [junit4] 2> 1599346 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event { > [junit4] 2> "id":"83d3815319262T3ndaag1h0zlskyji6jentgt3t", > [junit4] 2> "source":"node_added_trigger", > [junit4] 2> "eventTime":2319110896718434, > [junit4] 2> "eventType":"NODEADDED", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[2319110896718434], > [junit4] 2> "preferredOperation":"addreplica", > [junit4] 2> "_enqueue_time_":2319111898914944, > [junit4] 2> "nodeNames":["127.0.0.1:41057_solr"], > [junit4] 2> "replicaType":"NRT"}} > [junit4] 2> 1599346 DEBUG (ScheduledTrigger-14796-thread-2) [ ] > o.a.s.c.a.NodeAddedTrigger Removing new node from tracking: > 127.0.0.1:41057_solr > [junit4] 2> 1599346 DEBUG (AutoscalingActionExecutor-14797-thread-1) [ > ] o.a.s.c.a.ScheduledTriggers -- processing actions for { > [junit4] 2> "id":"83d3815319262T3ndaag1h0zlskyji6jentgt3t", > [junit4] 2> "source":"node_added_trigger", > [junit4] 2> "eventTime":2319110896718434, > [junit4] 2> "eventType":"NODEADDED", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[2319110896718434], > [junit4] 2> "preferredOperation":"addreplica", > [junit4] 2> "_enqueue_time_":2319111898914944, > [junit4] 2> "nodeNames":["127.0.0.1:41057_solr"], > [junit4] 2> "replicaType":"NRT"}} > [junit4] 2> 1599347 DEBUG (AutoscalingActionExecutor-14797-thread-1) [ > ] o.a.s.c.a.ComputePlanAction -- processing event: { > [junit4] 2> "id":"83d3815319262T3ndaag1h0zlskyji6jentgt3t", > [junit4] 2> "source":"node_added_trigger", > [junit4] 2> "eventTime":2319110896718434, > [junit4] 2> "eventType":"NODEADDED", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[2319110896718434], > [junit4] 2> "preferredOperation":"addreplica", > [junit4] 2> "_enqueue_time_":2319111898914944, > [junit4] 2> "nodeNames":["127.0.0.1:41057_solr"], > [junit4] 2> "replicaType":"NRT"}} with context properties: > {BEFORE_ACTION=[compute_plan]} > [junit4] 2> 1599389 INFO (qtp1580104046-24936) [n:127.0.0.1:41057_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} > status=0 QTime=0 > [junit4] 2> 1599391 INFO (qtp184603384-24876) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={wt=javabin&version=2&key=solr.core.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0.shard1.replica_n1:INDEX.sizeInBytes} > status=0 QTime=0 > [junit4] 2> 1599392 INFO (qtp184603384-24878) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} > status=0 QTime=0 > [junit4] 2> 1599394 DEBUG (AutoscalingActionExecutor-14797-thread-1) [ > ] o.a.s.c.a.ComputePlanAction Computed Plan: > action=ADDREPLICA&collection=testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0&shard=shard1&node=127.0.0.1:41057_solr&type=NRT > [junit4] 2> 1599394 DEBUG (AutoscalingActionExecutor-14797-thread-1) [ > ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending > event { > [junit4] 2> "id":"83d3815319262T3ndaag1h0zlskyji6jentgt3t", > [junit4] 2> "source":"node_added_trigger", > [junit4] 2> "eventTime":2319110896718434, > [junit4] 2> "eventType":"NODEADDED", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[2319110896718434], > [junit4] 2> "preferredOperation":"addreplica", > [junit4] 2> "_enqueue_time_":2319111898914944, > [junit4] 2> "nodeNames":["127.0.0.1:41057_solr"], > [junit4] 2> "replicaType":"NRT"}} > [junit4] 2> 1599394 DEBUG (AutoscalingActionExecutor-14797-thread-1) [ > ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending > event { > [junit4] 2> "id":"83d3815319262T3ndaag1h0zlskyji6jentgt3t", > [junit4] 2> "source":"node_added_trigger", > [junit4] 2> "eventTime":2319110896718434, > [junit4] 2> "eventType":"NODEADDED", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[2319110896718434], > [junit4] 2> "preferredOperation":"addreplica", > [junit4] 2> "_enqueue_time_":2319111898914944, > [junit4] 2> "nodeNames":["127.0.0.1:41057_solr"], > [junit4] 2> "replicaType":"NRT"}} > [junit4] 2> 1599397 INFO (qtp1580104046-24934) [n:127.0.0.1:41057_solr > ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with > params > collection.configName=conf&maxShardsPerNode=2&name=testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1&nrtReplicas=2&action=CREATE&numShards=1&wt=javabin&version=2 > and sendToOCPQueue=true > [junit4] 2> 1599398 DEBUG (AutoscalingActionExecutor-14797-thread-1) [ > ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending > event { > [junit4] 2> "id":"83d3815319262T3ndaag1h0zlskyji6jentgt3t", > [junit4] 2> "source":"node_added_trigger", > [junit4] 2> "eventTime":2319110896718434, > [junit4] 2> "eventType":"NODEADDED", > [junit4] 2> "properties":{ > [junit4] 2> "eventTimes":[2319110896718434], > [junit4] 2> "preferredOperation":"addreplica", > [junit4] 2> "_enqueue_time_":2319111898914944, > [junit4] 2> "nodeNames":["127.0.0.1:41057_solr"], > [junit4] 2> "replicaType":"NRT"}} > [junit4] 2> 1599398 DEBUG (AutoscalingActionExecutor-14797-thread-1) [ > ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger after > 5000ms > [junit4] 2> 1599398 DEBUG (AutoscalingActionExecutor-14797-thread-1) [ > ] o.a.s.c.a.ScheduledTriggers -- processing took 51 ms for event > id=83d3815319262T3ndaag1h0zlskyji6jentgt3t > [junit4] 2> 1599403 INFO > (OverseerThreadFactory-14798-thread-2-processing-n:127.0.0.1:41269_solr) > [n:127.0.0.1:41269_solr ] o.a.s.c.a.c.CreateCollectionCmd Create > collection testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1 > [junit4] 2> 1599408 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, > message = { > [junit4] 2> > "name":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1", > [junit4] 2> "fromApi":"true", > [junit4] 2> "collection.configName":"conf", > [junit4] 2> "numShards":"1", > [junit4] 2> "maxShardsPerNode":"2", > [junit4] 2> "nrtReplicas":"2", > [junit4] 2> "stateFormat":"2", > [junit4] 2> "replicationFactor":"2", > [junit4] 2> "operation":"create"} current state version: 0 > [junit4] 2> 1599408 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ClusterStateMutator building a new > cName: testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1 > [junit4] 2> 1599408 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ZkStateWriter going to > create_collection > /collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1/state.json > [junit4] 2> 1599513 INFO (qtp1580104046-24935) [n:127.0.0.1:41057_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} > status=0 QTime=0 > [junit4] 2> 1599514 INFO (qtp184603384-24880) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={wt=javabin&version=2&key=solr.core.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0.shard1.replica_n1:INDEX.sizeInBytes} > status=0 QTime=0 > [junit4] 2> 1599516 INFO (qtp184603384-24877) [n:127.0.0.1:41269_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics > params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} > status=0 QTime=0 > [junit4] 2> 1599521 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, > message = { > [junit4] 2> "operation":"ADDREPLICA", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n1", > [junit4] 2> "state":"down", > [junit4] 2> "base_url":"https://127.0.0.1:41057/solr";, > [junit4] 2> "node_name":"127.0.0.1:41057_solr", > [junit4] 2> "type":"NRT", > [junit4] 2> "waitForFinalState":"false"} current state version: 0 > [junit4] 2> 1599521 INFO > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.SliceMutator createReplica() { > [junit4] 2> "operation":"ADDREPLICA", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n1", > [junit4] 2> "state":"down", > [junit4] 2> "base_url":"https://127.0.0.1:41057/solr";, > [junit4] 2> "node_name":"127.0.0.1:41057_solr", > [junit4] 2> "type":"NRT", > [junit4] 2> "waitForFinalState":"false"} > [junit4] 2> 1599523 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ > [junit4] 2> "range":"80000000-7fffffff", > [junit4] 2> "state":"active", > [junit4] 2> "replicas":{}} > [junit4] 2> 1599523 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ > [junit4] 2> "range":"80000000-7fffffff", > [junit4] 2> "state":"active", > [junit4] 2> "replicas":{"core_node2":{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n1", > [junit4] 2> "base_url":"https://127.0.0.1:41057/solr";, > [junit4] 2> "state":"down", > [junit4] 2> "node_name":"127.0.0.1:41057_solr", > [junit4] 2> "type":"NRT"}}} > [junit4] 2> 1599525 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, > message = { > [junit4] 2> "operation":"ADDREPLICA", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3", > [junit4] 2> "state":"down", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "type":"NRT", > [junit4] 2> "waitForFinalState":"false"} current state version: 0 > [junit4] 2> 1599525 INFO > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.SliceMutator createReplica() { > [junit4] 2> "operation":"ADDREPLICA", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3", > [junit4] 2> "state":"down", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "type":"NRT", > [junit4] 2> "waitForFinalState":"false"} > [junit4] 2> 1599526 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{ > [junit4] 2> "range":"80000000-7fffffff", > [junit4] 2> "state":"active", > [junit4] 2> "replicas":{"core_node2":{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n1", > [junit4] 2> "base_url":"https://127.0.0.1:41057/solr";, > [junit4] 2> "state":"down", > [junit4] 2> "node_name":"127.0.0.1:41057_solr", > [junit4] 2> "type":"NRT"}}} > [junit4] 2> 1599527 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{ > [junit4] 2> "range":"80000000-7fffffff", > [junit4] 2> "state":"active", > [junit4] 2> "replicas":{ > [junit4] 2> "core_node2":{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n1", > [junit4] 2> "base_url":"https://127.0.0.1:41057/solr";, > [junit4] 2> "state":"down", > [junit4] 2> "node_name":"127.0.0.1:41057_solr", > [junit4] 2> "type":"NRT"}, > [junit4] 2> "core_node4":{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "state":"down", > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "type":"NRT"}}} > [junit4] 2> 1599627 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ZkStateWriter going to > update_collection > /collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1/state.json > version: 0 > [junit4] 2> 1599727 INFO (qtp184603384-24879) [n:127.0.0.1:41269_solr > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3 ] > o.a.s.h.a.CoreAdminOperation core create command > qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3&action=CREATE&numShards=1&collection=testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1&shard=shard1&wt=javabin&version=2&replicaType=NRT > [junit4] 2> 1599736 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, > message = { > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3", > [junit4] 2> "core_node_name":"core_node4", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"down", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false", > [junit4] 2> "operation":"state"} current state version: 0 > [junit4] 2> 1599736 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ReplicaMutator Update state > numShards=1 message={ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3", > [junit4] 2> "core_node_name":"core_node4", > [junit4] 2> "roles":null, > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "numShards":"1", > [junit4] 2> "state":"down", > [junit4] 2> "shard":"shard1", > [junit4] 2> > "collection":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false", > [junit4] 2> "operation":"state"} > [junit4] 2> 1599736 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ReplicaMutator Will update state for > replica: core_node4:{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "state":"down", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false"} > [junit4] 2> 1599736 DEBUG > (OverseerStateUpdate-72209578990960645-127.0.0.1:41269_solr-n_0000000000) > [n:127.0.0.1:41269_solr ] o.a.s.c.o.ReplicaMutator Collection is now: > DocCollection(testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1//collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1/state.json/1)={ > [junit4] 2> "pullReplicas":"0", > [junit4] 2> "replicationFactor":"2", > [junit4] 2> "router":{"name":"compositeId"}, > [junit4] 2> "maxShardsPerNode":"2", > [junit4] 2> "autoAddReplicas":"false", > [junit4] 2> "nrtReplicas":"2", > [junit4] 2> "tlogReplicas":"0", > [junit4] 2> "shards":{"shard1":{ > [junit4] 2> "range":"80000000-7fffffff", > [junit4] 2> "state":"active", > [junit4] 2> "replicas":{ > [junit4] 2> "core_node2":{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n1", > [junit4] 2> "base_url":"https://127.0.0.1:41057/solr";, > [junit4] 2> "state":"down", > [junit4] 2> "node_name":"127.0.0.1:41057_solr", > [junit4] 2> "type":"NRT"}, > [junit4] 2> "core_node4":{ > [junit4] 2> > "core":"testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3", > [junit4] 2> "base_url":"https://127.0.0.1:41269/solr";, > [junit4] 2> "node_name":"127.0.0.1:41269_solr", > [junit4] 2> "state":"down", > [junit4] 2> "type":"NRT", > [junit4] 2> "force_set_state":"false"}}}}} > [junit4] 2> 1599747 INFO (qtp184603384-24879) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1 s:shard1 > r:core_node4 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3 ] > o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0 > [junit4] 2> 1599776 INFO (qtp184603384-24879) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1 s:shard1 > r:core_node4 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3 ] > o.a.s.s.IndexSchema Schema name=minimal > [junit4] 2> 1599779 INFO (qtp184603384-24879) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1 s:shard1 > r:core_node4 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3 ] > o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id > [junit4] 2> 1599779 INFO (qtp184603384-24879) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1 s:shard1 > r:core_node4 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3 ] > o.a.s.c.CoreContainer Creating SolrCore > 'testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3' > using configuration from configset conf, trusted=true > [junit4] 2> 1599779 INFO (qtp184603384-24879) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1 s:shard1 > r:core_node4 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3 ] > o.a.s.m.r.SolrJmxReporter JMX monitoring for > 'solr_41269.solr.core.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1.shard1.replica_n3' > (registry > 'solr.core.testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1.shard1.replica_n3') > enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e6224d8 > [junit4] 2> 1599779 INFO (qtp184603384-24879) [n:127.0.0.1:41269_solr > c:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1 s:shard1 > r:core_node4 > x:testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3 ] > o.a.s.c.SolrCore > [[testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1_shard1_replica_n3] ] > Opening new SolrCore at > [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.aut > > [...truncated too long message...] > > e_1Shard_2/terms/shard1 > [junit4] 2> 5 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_4/terms/shard1 > [junit4] 2> 5 /solr/collections/testSelected3/state.json > [junit4] 2> 5 /solr/collections/testSelected2/state.json > [junit4] 2> 5 > /solr/collections/testNodeLostTriggerWithDeleteNodePreferredOp_0/state.json > [junit4] 2> 4 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_4/state.json > [junit4] 2> 4 /solr/collections/testSelected1/state.json > [junit4] 2> 4 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_3/state.json > [junit4] 2> 4 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_2/state.json > [junit4] 2> 4 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json > [junit4] 2> 3 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_1/state.json > [junit4] 2> 3 /solr/collections/testNodeAdded/terms/shard1 > [junit4] 2> 3 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/terms/shard1 > [junit4] 2> 2 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1/state.json > [junit4] 2> 2 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_0/terms/shard2 > [junit4] 2> 2 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_0/terms/shard1 > [junit4] 2> 2 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_2/state.json > [junit4] 2> 2 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_4/state.json > [junit4] 2> 2 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_0/terms/shard1 > [junit4] 2> 2 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_3/state.json > [junit4] 2> > [junit4] 2> Maximum concurrent data watches above limit: > [junit4] 2> > [junit4] 2> 114 /solr/collections/testSelected3/state.json > [junit4] 2> 111 /solr/collections/testSelected2/state.json > [junit4] 2> 106 /solr/collections/testSelected1/state.json > [junit4] 2> 54 > /solr/collections/testNodeWithMultipleReplicasLost/state.json > [junit4] 2> 54 > /solr/collections/testNodeLostTriggerWithDeleteNodePreferredOp_0/state.json > [junit4] 2> 52 /solr/aliases.json > [junit4] 2> 45 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_3/state.json > [junit4] 2> 45 /solr/autoscaling.json > [junit4] 2> 39 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_4/state.json > [junit4] 2> 34 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_3/state.json > [junit4] 2> 34 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_4/state.json > [junit4] 2> 32 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_2/state.json > [junit4] 2> 32 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_2/state.json > [junit4] 2> 30 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_3/state.json > [junit4] 2> 28 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_4/state.json > [junit4] 2> 28 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_1/state.json > [junit4] 2> 27 /solr/clusterstate.json > [junit4] 2> 27 /solr/clusterprops.json > [junit4] 2> 24 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_2/state.json > [junit4] 2> 24 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_1/state.json > [junit4] 2> 20 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_1/state.json > [junit4] 2> 17 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_2Shard_0/state.json > [junit4] 2> 16 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOpReplicaType_1Shard_0/state.json > [junit4] 2> 14 /solr/collections/testNodeAdded/state.json > [junit4] 2> 12 > /solr/collections/testNodeAddedTriggerWithAddReplicaPreferredOp_1Shard_0/state.json > [junit4] 2> 5 > /solr/overseer_elect/election/72209578990960645-127.0.0.1:41269_solr-n_0000000000 > [junit4] 2> 2 > /solr/overseer_elect/election/72209578990960682-127.0.0.1:43257_solr-n_0000000012 > [junit4] 2> 2 > /solr/overseer_elect/election/72209578990960655-127.0.0.1:37753_solr-n_0000000003 > [junit4] 2> 2 > /solr/overseer_elect/election/72209578990960688-127.0.0.1:35247_solr-n_0000000014 > [junit4] 2> 2 > /solr/overseer_elect/election/72209578990960667-127.0.0.1:37069_solr-n_0000000007 > [junit4] 2> 2 > /solr/overseer_elect/election/72209578990960673-127.0.0.1:43923_solr-n_0000000009 > [junit4] 2> > [junit4] 2> Maximum concurrent children watches above limit: > [junit4] 2> > [junit4] 2> 566 /solr/overseer/queue > [junit4] 2> 281 /solr/live_nodes > [junit4] 2> 166 /solr/collections > [junit4] 2> 161 /solr/overseer/collection-queue-work > [junit4] 2> 11 /solr/autoscaling/events/node_added_trigger > [junit4] 2> 9 /solr/autoscaling/events/node_lost_trigger > [junit4] 2> 6 > /solr/autoscaling/events/.scheduled_maintenance > [junit4] 2> 6 /solr/autoscaling/events/.auto_add_replicas > [junit4] 2> 6 /solr/overseer/queue-work > [junit4] 2> > [junit4] 2> 1690329 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:36511 > [junit4] 2> 1690329 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36511 > [junit4] 2> 1690329 INFO > (SUITE-ComputePlanActionTest-seed#[E4460E44D1340265]-worker) [ ] > o.a.s.c.ZkTestServer connecting to 127.0.0.1 36511 > [junit4] 2> NOTE: leaving temporary files on disk at: > /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ComputePlanActionTest_E4460E44D1340265-001 > [junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {}, > docValues:{}, maxPointsInLeafNode=384, maxMBSortInHeap=5.90285115006686, > sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=he-IL, > timezone=America/Denver > [junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation > 1.8.0_252 (64-bit)/cpus=4,threads=1,free=185688376,total=516947968 > [junit4] 2> NOTE: All tests run in this JVM: [AnalysisErrorHandlingTest, > TestSolrConfigHandler, TestExpandComponent, TestMacroExpander, > SchemaVersionSpecificBehaviorTest, SuggesterTest, TestStressInPlaceUpdates, > UUIDUpdateProcessorFallbackTest, HttpSolrCallGetCoreTest, > TestSlowCompositeReaderWrapper, LukeRequestHandlerTest, > CollectionsAPIAsyncDistributedZkTest, TestFieldTypeResource, DateFieldTest, > TestObjectReleaseTracker, TestPKIAuthenticationPlugin, > UnloadDistributedZkTest, BasicAuthIntegrationTest, TestExactStatsCache, > TestManagedSchemaAPI, TestManagedSynonymFilterFactory, > TestInPlaceUpdateWithRouteField, TimeZoneUtilsTest, TestIBSimilarityFactory, > InfixSuggestersTest, TestLuceneMatchVersion, TestBooleanSimilarityFactory, > AnalyticsMergeStrategyTest, TestImplicitCoreProperties, TestSolrXml, > TestCloudSearcherWarming, TestConfigSetsAPIZkFailure, TestLazyCores, > DistributedMLTComponentTest, TestQuerySenderNoQuery, TestPullReplica, > SolrCoreTest, TestFieldSortValues, DocumentBuilderTest, > ClassificationUpdateProcessorFactoryTest, TestSolrCoreSnapshots, > TestSimClusterStateProvider, SplitShardTest, RestoreTriggerStateTest, > TestSolrCoreProperties, CustomCollectionTest, TestGraphMLResponseWriter, > DistanceUnitsTest, QueryEqualityTest, TestQueryTypes, > OutOfBoxZkACLAndCredentialsProvidersTest, ReplaceNodeNoTargetTest, > TestCollationField, SolrMetricsIntegrationTest, RollingRestartTest, > TestNestedDocsSort, TestValueSourceCache, TestRawResponseWriter, > TestStressLucene, TestDocSet, TestFunctionQuery, DeleteStatusTest, > TestTextField, TestSegmentSorting, PathHierarchyTokenizerFactoryTest, > TestSimTriggerIntegration, ChaosMonkeySafeLeaderWithPullReplicasTest, > TestSolrJacksonAnnotation, TestHashPartitioner, TestFieldCacheSortRandom, > TestCloudPivotFacet, TestSolr4Spatial2, MetricUtilsTest, > CdcrBidirectionalTest, Tagger2Test, TestNumericRangeQuery64, > HLLSerializationTest, AuthToolTest, ThreadDumpHandlerTest, > HttpPartitionWithTlogReplicasTest, TestSolrCloudWithDelegationTokens, > PropertiesRequestHandlerTest, TestLegacyNumericUtils, TestRecoveryHdfs, > ClusterStateMockUtilTest, TestBlendedInfixSuggestions, > RecoveryAfterSoftCommitTest, SpatialFilterTest, TestSchemaVersionResource, > ZkFailoverTest, BufferStoreTest, TestMinHashQParser, > SearchRateTriggerIntegrationTest, TestSimExecutePlanAction, > HdfsBasicDistributedZk2Test, StatelessScriptUpdateProcessorFactoryTest, > BooleanFieldTest, TestWithCollection, TestDistributedMap, > HdfsWriteToMultipleCollectionsTest, OverseerTaskQueueTest, > DistributedFacetPivotLongTailTest, SubstringBytesRefFilterTest, > DistributedSpellCheckComponentTest, TestSimNodeAddedTrigger, > MetricsConfigTest, TestLuceneIndexBackCompat, OrderedExecutorTest, > TestChildDocTransformer, SpellCheckCollatorTest, MissingSegmentRecoveryTest, > TestSimpleTrackingShardHandler, BasicDistributedZk2Test, > ChaosMonkeyNothingIsSafeTest, ConnectionManagerTest, > DeleteInactiveReplicaTest, DistributedQueueTest, DocValuesNotIndexedTest, > ForceLeaderTest, ForceLeaderWithTlogReplicasTest, > FullSolrCloudDistribCmdsTest, LeaderFailoverAfterPartitionTest, > MetricsHistoryWithAuthIntegrationTest, MultiThreadedOCPTest, > SolrCloudExampleTest, TestConfigSetsAPIExclusivity, TestLockTree, > TestMiniSolrCloudClusterSSL, TestRebalanceLeaders, TestUtilizeNode, > SimpleCollectionCreateDeleteTest, TestCollectionsAPIViaSolrCloudCluster, > AutoScalingHandlerTest, ComputePlanActionTest] > [junit4] Completed [576/919 (1!)] on J0 in 96.79s, 9 tests, 1 error, 1 > skipped <<< FAILURES! > > [...truncated 53503 lines...] > > --------------------------------------------------------------------- > To unsubscribe, e-mail: builds-unsubscr...@lucene.apache.org > For additional commands, e-mail: builds-h...@lucene.apache.org
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
