Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/27614/ Java: 64bit/jdk-13.0.2 -XX:-UseCompressedOops -XX:+UseParallelGC
2 tests failed. FAILED: org.apache.solr.servlet.TestRequestRateLimiter.testConcurrentQueries Error Message: Incoming accepted new request count did not match. Expected 5 incoming 25 Stack Trace: java.lang.AssertionError: Incoming accepted new request count did not match. Expected 5 incoming 25 at __randomizedtesting.SeedInfo.seed([B7E8EE7471B77050:F5730D51F1D00571]:0) at org.junit.Assert.fail(Assert.java:88) at org.junit.Assert.assertTrue(Assert.java:41) at org.apache.solr.servlet.TestRequestRateLimiter.testConcurrentQueries(TestRequestRateLimiter.java:74) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:567) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) 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:370) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) at java.base/java.lang.Thread.run(Thread.java:830) FAILED: org.apache.solr.servlet.TestRequestRateLimiter.testSlotBorrowing Error Message: Incoming slots borrowed count did not match. Expected > 0 incoming 0 Stack Trace: java.lang.AssertionError: Incoming slots borrowed count did not match. Expected > 0 incoming 0 at __randomizedtesting.SeedInfo.seed([B7E8EE7471B77050:6FF084B1EE18BF4]:0) at org.junit.Assert.fail(Assert.java:88) at org.junit.Assert.assertTrue(Assert.java:41) at org.apache.solr.servlet.TestRequestRateLimiter.testSlotBorrowing(TestRequestRateLimiter.java:106) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:567) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) 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:370) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) at java.base/java.lang.Thread.run(Thread.java:830) Build Log: [...truncated 13020 lines...] [junit4] Suite: org.apache.solr.servlet.TestRequestRateLimiter [junit4] 2> 226619 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/solr/configsets/_default/conf' [junit4] 2> 226619 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J3/temp/solr.servlet.TestRequestRateLimiter_B7E8EE7471B77050-001/data-dir-53-001 [junit4] 2> 226619 WARN (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 226619 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 226620 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 226621 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 226621 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J3/temp/solr.servlet.TestRequestRateLimiter_B7E8EE7471B77050-001/tempDir-001 [junit4] 2> 226621 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 226621 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0 [junit4] 2> 226621 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 226622 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0. [junit4] 2> 226721 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 34615 [junit4] 2> 226721 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:34615 [junit4] 2> 226721 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34615 [junit4] 2> 226721 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34615 [junit4] 2> 226724 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 226727 INFO (zkConnectionManagerCallback-3151-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 226727 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 226734 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 226736 INFO (zkConnectionManagerCallback-3153-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 226736 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 226741 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 226746 INFO (zkConnectionManagerCallback-3155-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 226746 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 226877 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 226877 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 226877 INFO (jetty-launcher-3156-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 13.0.2+8 [junit4] 2> 226878 INFO (jetty-launcher-3156-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 226878 INFO (jetty-launcher-3156-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 226878 INFO (jetty-launcher-3156-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 226878 INFO (jetty-launcher-3156-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3621b668{/solr,null,AVAILABLE} [junit4] 2> 226880 INFO (jetty-launcher-3156-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6c2daae4{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:34691} [junit4] 2> 226880 INFO (jetty-launcher-3156-thread-1) [ ] o.e.j.s.Server Started @226907ms [junit4] 2> 226880 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34691} [junit4] 2> 226880 ERROR (jetty-launcher-3156-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 226880 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 226880 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0 [junit4] 2> 226880 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 226880 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr [junit4] 2> 226880 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-08-10T06:44:52.498740Z [junit4] 2> 226881 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 226882 INFO (zkConnectionManagerCallback-3158-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 226883 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 226887 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 226992 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 226994 WARN (jetty-launcher-3156-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6b0cc41e[provider=null,keyStore=null,trustStore=null] [junit4] 2> 226994 WARN (jetty-launcher-3156-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6b0cc41e[provider=null,keyStore=null,trustStore=null] [junit4] 2> 226996 WARN (jetty-launcher-3156-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@48c86d06[provider=null,keyStore=null,trustStore=null] [junit4] 2> 226996 WARN (jetty-launcher-3156-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@48c86d06[provider=null,keyStore=null,trustStore=null] [junit4] 2> 226997 INFO (jetty-launcher-3156-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34615/solr [junit4] 2> 226998 INFO (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 227000 INFO (zkConnectionManagerCallback-3168-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 227001 INFO (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 227038 INFO (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34691_solr [junit4] 2> 227039 INFO (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.c.Overseer Overseer (id=72174184860614660-127.0.0.1:34691_solr-n_0000000000) starting [junit4] 2> 227041 INFO (OverseerStateUpdate-72174184860614660-127.0.0.1:34691_solr-n_0000000000) [n:127.0.0.1:34691_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:34691_solr [junit4] 2> 227041 INFO (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34691_solr [junit4] 2> 227042 INFO (OverseerStateUpdate-72174184860614660-127.0.0.1:34691_solr-n_0000000000) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 227043 INFO (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 227043 WARN (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_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> 227048 INFO (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 227059 INFO (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34691.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d72ae0f [junit4] 2> 227063 INFO (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34691.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d72ae0f [junit4] 2> 227063 INFO (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34691.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d72ae0f [junit4] 2> 227064 INFO (jetty-launcher-3156-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J3/temp/solr.servlet.TestRequestRateLimiter_B7E8EE7471B77050-001/tempDir-001/node1 [junit4] 2> 227067 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1 [junit4] 2> 227067 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 227068 INFO (zkConnectionManagerCallback-3183-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 227068 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 227069 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 227069 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34615/solr ready [junit4] 2> 227078 INFO (TEST-TestRequestRateLimiter.testConcurrentQueries-seed#[B7E8EE7471B77050]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testConcurrentQueries [junit4] 2> 227087 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&name=c1&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 227097 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection c1 [junit4] 2> 227097 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/c1.AUTOCREATED/protwords.txt [junit4] 2> 227098 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/c1.AUTOCREATED/managed-schema [junit4] 2> 227099 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/c1.AUTOCREATED/solrconfig.xml [junit4] 2> 227100 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/c1.AUTOCREATED/lang/contractions_it.txt [junit4] 2> 227101 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/c1.AUTOCREATED/lang/stemdict_nl.txt [junit4] 2> 227101 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/c1.AUTOCREATED/lang/stopwords_hy.txt [junit4] 2> 227102 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/c1.AUTOCREATED/lang/stopwords_no.txt [junit4] 2> 227104 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/c1.AUTOCREATED/lang/contractions_ca.txt [junit4] 2> 227105 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/c1.AUTOCREATED/lang/stopwords_id.txt [junit4] 2> 227106 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/c1.AUTOCREATED/lang/stopwords_et.txt [junit4] 2> 227106 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/c1.AUTOCREATED/lang/stopwords_ga.txt [junit4] 2> 227107 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/c1.AUTOCREATED/lang/stopwords_hi.txt [junit4] 2> 227108 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/c1.AUTOCREATED/lang/stopwords_da.txt [junit4] 2> 227109 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/c1.AUTOCREATED/lang/stopwords_ja.txt [junit4] 2> 227110 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/c1.AUTOCREATED/lang/stopwords_pt.txt [junit4] 2> 227111 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/c1.AUTOCREATED/lang/stopwords_hu.txt [junit4] 2> 227112 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/c1.AUTOCREATED/lang/stopwords_el.txt [junit4] 2> 227112 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/c1.AUTOCREATED/lang/stopwords_ru.txt [junit4] 2> 227113 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/c1.AUTOCREATED/lang/stopwords_tr.txt [junit4] 2> 227114 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/c1.AUTOCREATED/lang/stopwords_ar.txt [junit4] 2> 227115 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/c1.AUTOCREATED/lang/userdict_ja.txt [junit4] 2> 227115 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/c1.AUTOCREATED/lang/stopwords_eu.txt [junit4] 2> 227116 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/c1.AUTOCREATED/lang/stopwords_it.txt [junit4] 2> 227116 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/c1.AUTOCREATED/lang/stopwords_cz.txt [junit4] 2> 227117 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/c1.AUTOCREATED/lang/stoptags_ja.txt [junit4] 2> 227118 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/c1.AUTOCREATED/lang/contractions_fr.txt [junit4] 2> 227119 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/c1.AUTOCREATED/lang/stopwords_de.txt [junit4] 2> 227119 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/c1.AUTOCREATED/lang/stopwords_fa.txt [junit4] 2> 227120 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/c1.AUTOCREATED/lang/contractions_ga.txt [junit4] 2> 227121 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/c1.AUTOCREATED/lang/stopwords_ca.txt [junit4] 2> 227121 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/c1.AUTOCREATED/lang/stopwords_nl.txt [junit4] 2> 227122 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/c1.AUTOCREATED/lang/stopwords_bg.txt [junit4] 2> 227122 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/c1.AUTOCREATED/lang/stopwords_lv.txt [junit4] 2> 227123 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/c1.AUTOCREATED/lang/stopwords_en.txt [junit4] 2> 227123 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/c1.AUTOCREATED/lang/stopwords_es.txt [junit4] 2> 227124 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/c1.AUTOCREATED/lang/stopwords_th.txt [junit4] 2> 227125 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/c1.AUTOCREATED/lang/stopwords_ro.txt [junit4] 2> 227125 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/c1.AUTOCREATED/lang/stopwords_fr.txt [junit4] 2> 227126 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/c1.AUTOCREATED/lang/stopwords_sv.txt [junit4] 2> 227126 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/c1.AUTOCREATED/lang/stopwords_fi.txt [junit4] 2> 227127 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/c1.AUTOCREATED/lang/hyphenations_ga.txt [junit4] 2> 227127 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/c1.AUTOCREATED/lang/stopwords_gl.txt [junit4] 2> 227128 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/c1.AUTOCREATED/synonyms.txt [junit4] 2> 227128 INFO (OverseerThreadFactory-3173-thread-1-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/c1.AUTOCREATED/stopwords.txt [junit4] 2> 227232 INFO (OverseerStateUpdate-72174184860614660-127.0.0.1:34691_solr-n_0000000000) [n:127.0.0.1:34691_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"c1", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"c1_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"https://127.0.0.1:34691/solr", [junit4] 2> "node_name":"127.0.0.1:34691_solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 227443 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr x:c1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=c1.AUTOCREATED&newCollection=true&name=c1_shard1_replica_n1&action=CREATE&numShards=1&collection=c1&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 227443 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr x:c1_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores [junit4] 2> 227450 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 227470 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'c1_shard1_replica_n1' using configuration from configset c1.AUTOCREATED, trusted=true [junit4] 2> 227473 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config [junit4] 2> 227576 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id [junit4] 2> 227577 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34691.solr.core.c1.shard1.replica_n1' (registry 'solr.core.c1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d72ae0f [junit4] 2> 227577 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.SolrCore [[c1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J3/temp/solr.servlet.TestRequestRateLimiter_B7E8EE7471B77050-001/tempDir-001/node1/c1_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J3/temp/solr.servlet.TestRequestRateLimiter_B7E8EE7471B77050-001/tempDir-001/node1/c1_shard1_replica_n1/data/] [junit4] 2> 227609 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 227609 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 227611 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms; [junit4] 2> 227611 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 227612 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/c1.AUTOCREATED [junit4] 2> 227612 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/c1.AUTOCREATED [junit4] 2> 227612 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/c1.AUTOCREATED/managed-schema [junit4] 2> 227612 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest [junit4] 2> 227613 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 227613 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default, field=_text_, classname=solr.DirectSolrSpellChecker, distanceMeasure=internal, accuracy=0.5, maxEdits=2, minPrefix=1, maxInspections=5, minQueryLength=4, maxQueryFrequency=0.01} [junit4] 2> 227613 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 227613 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1674619800236589056 [junit4] 2> 227614 INFO (searcherExecutor-3185-thread-1-processing-n:127.0.0.1:34691_solr x:c1_shard1_replica_n1 c:c1 s:shard1 r:core_node2) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done. [junit4] 2> 227614 INFO (searcherExecutor-3185-thread-1-processing-n:127.0.0.1:34691_solr x:c1_shard1_replica_n1 c:c1 s:shard1 r:core_node2) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 227615 INFO (searcherExecutor-3185-thread-1-processing-n:127.0.0.1:34691_solr x:c1_shard1_replica_n1 c:c1 s:shard1 r:core_node2) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.SolrCore [c1_shard1_replica_n1] Registered new searcher autowarm time: 0 ms [junit4] 2> 227616 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/c1/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 227616 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/c1/leaders/shard1 [junit4] 2> 227617 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 227617 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 227617 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34691/solr/c1_shard1_replica_n1/ [junit4] 2> 227618 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 227618 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:34691/solr/c1_shard1_replica_n1/ has no replicas [junit4] 2> 227619 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/c1/leaders/shard1/leader after winning as /collections/c1/leader_elect/shard1/election/72174184860614660-core_node2-n_0000000000 [junit4] 2> 227619 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34691/solr/c1_shard1_replica_n1/ shard1 [junit4] 2> 227720 INFO (zkCallback-3167-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [1]) [junit4] 2> 227720 INFO (zkCallback-3167-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [1]) [junit4] 2> 227721 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 227722 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=c1.AUTOCREATED&newCollection=true&name=c1_shard1_replica_n1&action=CREATE&numShards=1&collection=c1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=278 [junit4] 2> 227723 INFO (qtp601311957-5707) [n:127.0.0.1:34691_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> 227822 INFO (zkCallback-3167-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [1]) [junit4] 2> 227822 INFO (zkCallback-3167-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [1]) [junit4] 2> 227822 INFO (zkCallback-3167-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/c1/state.json] for collection [c1] has occurred - updating... (live nodes size: [1]) [junit4] 2> 227823 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&name=c1&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=735 [junit4] 2> 227824 INFO (TEST-TestRequestRateLimiter.testConcurrentQueries-seed#[B7E8EE7471B77050]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: c1 [junit4] 2> 227869 INFO (zkCallback-3167-thread-1) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/c1.AUTOCREATED/managed-schema, has occurred - updating schema from ZooKeeper ... [junit4] 2> 227869 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Persisted managed schema version 1 at /configs/c1.AUTOCREATED/managed-schema [junit4] 2> 227869 INFO (zkCallback-3167-thread-1) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from Zookeeper [junit4] 2> 227874 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/c1/terms/shard1 to Terms{values={core_node2=1}, version=1} [junit4] 2> 227874 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0 (1674619800505024512)]} 0 47 [junit4] 2> 227877 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1674619800512364544)]} 0 1 [junit4] 2> 227877 INFO (zkCallback-3167-thread-1) [ ] o.a.s.s.IndexSchema Schema name=default-config [junit4] 2> 227877 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2 (1674619800513413120)]} 0 0 [junit4] 2> 227878 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3 (1674619800514461696)]} 0 0 [junit4] 2> 227879 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4 (1674619800514461697)]} 0 0 [junit4] 2> 227879 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5 (1674619800515510272)]} 0 0 [junit4] 2> 227879 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6 (1674619800515510273)]} 0 0 [junit4] 2> 227880 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7 (1674619800516558848)]} 0 0 [junit4] 2> 227880 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8 (1674619800516558849)]} 0 0 [junit4] 2> 227881 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9 (1674619800517607424)]} 0 0 [junit4] 2> 227881 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10 (1674619800517607425)]} 0 0 [junit4] 2> 227881 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11 (1674619800517607426)]} 0 0 [junit4] 2> 227882 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12 (1674619800518656000)]} 0 0 [junit4] 2> 227882 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13 (1674619800518656001)]} 0 0 [junit4] 2> 227883 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14 (1674619800519704576)]} 0 0 [junit4] 2> 227883 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15 (1674619800519704577)]} 0 0 [junit4] 2> 227883 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16 (1674619800519704578)]} 0 0 [junit4] 2> 227884 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17 (1674619800520753152)]} 0 0 [junit4] 2> 227884 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18 (1674619800520753153)]} 0 0 [junit4] 2> 227885 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19 (1674619800521801728)]} 0 0 [junit4] 2> 227885 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20 (1674619800521801729)]} 0 0 [junit4] 2> 227886 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21 (1674619800522850304)]} 0 0 [junit4] 2> 227886 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22 (1674619800522850305)]} 0 0 [junit4] 2> 227887 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23 (1674619800523898880)]} 0 0 [junit4] 2> 227887 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24 (1674619800523898881)]} 0 0 [junit4] 2> 227888 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25 (1674619800524947456)]} 0 0 [junit4] 2> 227888 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26 (1674619800524947457)]} 0 0 [junit4] 2> 227889 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27 (1674619800525996032)]} 0 0 [junit4] 2> 227889 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28 (1674619800525996033)]} 0 0 [junit4] 2> 227890 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29 (1674619800527044608)]} 0 0 [junit4] 2> 227890 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30 (1674619800527044609)]} 0 0 [junit4] 2> 227891 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31 (1674619800528093184)]} 0 0 [junit4] 2> 227892 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32 (1674619800529141760)]} 0 0 [junit4] 2> 227892 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33 (1674619800529141761)]} 0 0 [junit4] 2> 227893 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34 (1674619800530190336)]} 0 0 [junit4] 2> 227893 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35 (1674619800530190337)]} 0 0 [junit4] 2> 227894 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36 (1674619800531238912)]} 0 0 [junit4] 2> 227894 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37 (1674619800531238913)]} 0 0 [junit4] 2> 227895 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38 (1674619800532287488)]} 0 0 [junit4] 2> 227896 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39 (1674619800533336064)]} 0 0 [junit4] 2> 227896 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40 (1674619800533336065)]} 0 0 [junit4] 2> 227897 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41 (1674619800534384640)]} 0 0 [junit4] 2> 227898 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42 (1674619800535433216)]} 0 0 [junit4] 2> 227899 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43 (1674619800536481792)]} 0 0 [junit4] 2> 227900 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44 (1674619800537530368)]} 0 0 [junit4] 2> 227901 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45 (1674619800537530369)]} 0 0 [junit4] 2> 227901 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46 (1674619800538578944)]} 0 0 [junit4] 2> 227902 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47 (1674619800539627520)]} 0 0 [junit4] 2> 227903 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48 (1674619800540676096)]} 0 0 [junit4] 2> 227904 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49 (1674619800540676097)]} 0 0 [junit4] 2> 227904 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50 (1674619800541724672)]} 0 0 [junit4] 2> 227905 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51 (1674619800542773248)]} 0 0 [junit4] 2> 227906 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52 (1674619800543821824)]} 0 0 [junit4] 2> 227907 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53 (1674619800544870400)]} 0 0 [junit4] 2> 227908 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54 (1674619800545918976)]} 0 0 [junit4] 2> 227909 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55 (1674619800545918977)]} 0 0 [junit4] 2> 227909 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56 (1674619800546967552)]} 0 0 [junit4] 2> 227916 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57 (1674619800553259008)]} 0 0 [junit4] 2> 227917 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58 (1674619800555356160)]} 0 0 [junit4] 2> 227917 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59 (1674619800555356161)]} 0 0 [junit4] 2> 227918 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60 (1674619800556404736)]} 0 0 [junit4] 2> 227918 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61 (1674619800556404737)]} 0 0 [junit4] 2> 227918 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62 (1674619800556404738)]} 0 0 [junit4] 2> 227919 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63 (1674619800557453312)]} 0 0 [junit4] 2> 227919 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64 (1674619800557453313)]} 0 0 [junit4] 2> 227920 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65 (1674619800558501888)]} 0 0 [junit4] 2> 227920 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66 (1674619800558501889)]} 0 0 [junit4] 2> 227921 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67 (1674619800559550464)]} 0 0 [junit4] 2> 227921 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68 (1674619800559550465)]} 0 0 [junit4] 2> 227921 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69 (1674619800559550466)]} 0 0 [junit4] 2> 227922 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70 (1674619800560599040)]} 0 0 [junit4] 2> 227922 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71 (1674619800560599041)]} 0 0 [junit4] 2> 227923 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72 (1674619800561647616)]} 0 0 [junit4] 2> 227923 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73 (1674619800561647617)]} 0 0 [junit4] 2> 227924 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74 (1674619800562696192)]} 0 0 [junit4] 2> 227924 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75 (1674619800562696193)]} 0 0 [junit4] 2> 227925 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76 (1674619800563744768)]} 0 0 [junit4] 2> 227925 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77 (1674619800563744769)]} 0 0 [junit4] 2> 227926 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78 (1674619800563744770)]} 0 0 [junit4] 2> 227926 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79 (1674619800564793344)]} 0 0 [junit4] 2> 227926 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80 (1674619800564793345)]} 0 0 [junit4] 2> 227927 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81 (1674619800565841920)]} 0 0 [junit4] 2> 227927 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82 (1674619800565841921)]} 0 0 [junit4] 2> 227928 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83 (1674619800566890496)]} 0 0 [junit4] 2> 227928 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84 (1674619800566890497)]} 0 0 [junit4] 2> 227929 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85 (1674619800567939072)]} 0 0 [junit4] 2> 227929 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86 (1674619800567939073)]} 0 0 [junit4] 2> 227930 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[87 (1674619800568987648)]} 0 0 [junit4] 2> 227930 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[88 (1674619800568987649)]} 0 0 [junit4] 2> 227931 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[89 (1674619800570036224)]} 0 0 [junit4] 2> 227931 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[90 (1674619800570036225)]} 0 0 [junit4] 2> 227932 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[91 (1674619800571084800)]} 0 0 [junit4] 2> 227933 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92 (1674619800571084801)]} 0 0 [junit4] 2> 227933 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[93 (1674619800572133376)]} 0 0 [junit4] 2> 227933 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[94 (1674619800572133377)]} 0 0 [junit4] 2> 227934 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95 (1674619800573181952)]} 0 0 [junit4] 2> 227934 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[96 (1674619800573181953)]} 0 0 [junit4] 2> 227935 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[97 (1674619800574230528)]} 0 0 [junit4] 2> 227935 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[98 (1674619800574230529)]} 0 0 [junit4] 2> 227936 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[99 (1674619800575279104)]} 0 0 [junit4] 2> 227940 INFO (searcherExecutor-3185-thread-1-processing-n:127.0.0.1:34691_solr x:c1_shard1_replica_n1 c:c1 s:shard1 r:core_node2) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done. [junit4] 2> 227941 INFO (searcherExecutor-3185-thread-1-processing-n:127.0.0.1:34691_solr x:c1_shard1_replica_n1 c:c1 s:shard1 r:core_node2) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.SolrCore [c1_shard1_replica_n1] Registered new searcher autowarm time: 0 ms [junit4] 2> 227941 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [c1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=c1:4&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 4 [junit4] 2> 227942 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227955 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227956 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227957 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227968 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227968 INFO (qtp601311957-5736) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227969 INFO (qtp601311957-5731) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227969 INFO (qtp601311957-5734) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227971 INFO (qtp601311957-5735) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227981 INFO (qtp601311957-5736) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227982 INFO (qtp601311957-5736) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227987 INFO (qtp601311957-5731) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227989 INFO (qtp601311957-5736) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227993 INFO (qtp601311957-5709) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227993 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227993 INFO (qtp601311957-5734) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227993 INFO (qtp601311957-5739) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227993 INFO (qtp601311957-5734) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227994 INFO (qtp601311957-5739) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227994 INFO (qtp601311957-5734) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227995 INFO (qtp601311957-5739) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227999 INFO (qtp601311957-5707) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227999 INFO (qtp601311957-5744) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 227999 INFO (qtp601311957-5735) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 228000 INFO (qtp601311957-5736) [n:127.0.0.1:34691_solr c:c1 s:shard1 r:core_node2 x:c1_shard1_replica_n1 ] o.a.s.c.S.Request [c1_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=c1:4&wt=javabin&version=2} hits=100 status=0 QTime=0 [junit4] 2> 228002 INFO (TEST-TestRequestRateLimiter.testConcurrentQueries-seed#[B7E8EE7471B77050]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testConcurrentQueries [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRequestRateLimiter -Dtests.method=testConcurrentQueries -Dtests.seed=B7E8EE7471B77050 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-SN -Dtests.timezone=Canada/Central -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 0.93s J3 | TestRequestRateLimiter.testConcurrentQueries <<< [junit4] > Throwable #1: java.lang.AssertionError: Incoming accepted new request count did not match. Expected 5 incoming 25 [junit4] > at __randomizedtesting.SeedInfo.seed([B7E8EE7471B77050:F5730D51F1D00571]:0) [junit4] > at org.apache.solr.servlet.TestRequestRateLimiter.testConcurrentQueries(TestRequestRateLimiter.java:74) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567) [junit4] > at java.base/java.lang.Thread.run(Thread.java:830) [junit4] 2> 228040 INFO (TEST-TestRequestRateLimiter.testSlotBorrowing-seed#[B7E8EE7471B77050]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSlotBorrowing [junit4] 2> 228041 INFO (qtp601311957-5731) [n:127.0.0.1:34691_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&name=c2&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 228044 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection c2 [junit4] 2> 228045 INFO (OverseerCollectionConfigSetProcessor-72174184860614660-127.0.0.1:34691_solr-n_0000000000) [n:127.0.0.1:34691_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> 228045 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/c2.AUTOCREATED/protwords.txt [junit4] 2> 228047 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/c2.AUTOCREATED/managed-schema [junit4] 2> 228048 INFO (zkCallback-3167-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id [junit4] 2> 228048 INFO (zkCallback-3167-thread-1) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 179 ms [junit4] 2> 228048 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/c2.AUTOCREATED/solrconfig.xml [junit4] 2> 228050 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/c2.AUTOCREATED/lang/contractions_it.txt [junit4] 2> 228052 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/c2.AUTOCREATED/lang/stemdict_nl.txt [junit4] 2> 228055 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/c2.AUTOCREATED/lang/stopwords_hy.txt [junit4] 2> 228057 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/c2.AUTOCREATED/lang/stopwords_no.txt [junit4] 2> 228059 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/c2.AUTOCREATED/lang/contractions_ca.txt [junit4] 2> 228059 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/c2.AUTOCREATED/lang/stopwords_id.txt [junit4] 2> 228067 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/c2.AUTOCREATED/lang/stopwords_et.txt [junit4] 2> 228068 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/c2.AUTOCREATED/lang/stopwords_ga.txt [junit4] 2> 228069 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/c2.AUTOCREATED/lang/stopwords_hi.txt [junit4] 2> 228070 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/c2.AUTOCREATED/lang/stopwords_da.txt [junit4] 2> 228070 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/c2.AUTOCREATED/lang/stopwords_ja.txt [junit4] 2> 228071 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/c2.AUTOCREATED/lang/stopwords_pt.txt [junit4] 2> 228072 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/c2.AUTOCREATED/lang/stopwords_hu.txt [junit4] 2> 228072 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/c2.AUTOCREATED/lang/stopwords_el.txt [junit4] 2> 228073 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/c2.AUTOCREATED/lang/stopwords_ru.txt [junit4] 2> 228073 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/c2.AUTOCREATED/lang/stopwords_tr.txt [junit4] 2> 228074 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/c2.AUTOCREATED/lang/stopwords_ar.txt [junit4] 2> 228074 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/c2.AUTOCREATED/lang/userdict_ja.txt [junit4] 2> 228075 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/c2.AUTOCREATED/lang/stopwords_eu.txt [junit4] 2> 228076 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1:34691_solr) [n:127.0.0.1:34691_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/c2.AUTOCREATED/lang/stopwords_it.txt [junit4] 2> 228083 INFO (OverseerThreadFactory-3173-thread-2-processing-n:127.0.0.1: [...truncated too long message...] a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.c1.shard1.replica_n1 tag=SolrCore@7a4014c3 [junit4] 2> 229170 INFO (coreCloseExecutor-3202-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2768115d: rootName = solr_34691, domain = solr.core.c1.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.c1.shard1.replica_n1/com.codahale.metrics.MetricRegistry@39564804 [junit4] 2> 229174 INFO (coreCloseExecutor-3202-thread-2) [n:127.0.0.1:34691_solr ] o.a.s.c.SolrCore [c2_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@394b8557 [junit4] 2> 229176 INFO (coreCloseExecutor-3202-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.c1.shard1.leader tag=SolrCore@7a4014c3 [junit4] 2> 229176 INFO (coreCloseExecutor-3202-thread-2) [n:127.0.0.1:34691_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.c2.shard1.replica_n1 tag=SolrCore@394b8557 [junit4] 2> 229176 INFO (coreCloseExecutor-3202-thread-2) [n:127.0.0.1:34691_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@71575ab: rootName = solr_34691, domain = solr.core.c2.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.c2.shard1.replica_n1/com.codahale.metrics.MetricRegistry@5bcb677 [junit4] 2> 229177 INFO (coreCloseExecutor-3202-thread-1) [n:127.0.0.1:34691_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary). [junit4] 2> 229183 INFO (coreCloseExecutor-3202-thread-2) [n:127.0.0.1:34691_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.c2.shard1.leader tag=SolrCore@394b8557 [junit4] 2> 229183 INFO (coreCloseExecutor-3202-thread-2) [n:127.0.0.1:34691_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary). [junit4] 2> 229184 INFO (jetty-closer-3198-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null [junit4] 2> 229184 INFO (jetty-closer-3198-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@645f75bf: rootName = solr_34691, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@3acfb1b6 [junit4] 2> 229186 INFO (jetty-closer-3198-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null [junit4] 2> 229186 INFO (jetty-closer-3198-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2a71df03: rootName = solr_34691, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@56eca38 [junit4] 2> 229188 INFO (jetty-closer-3198-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null [junit4] 2> 229188 INFO (jetty-closer-3198-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@85b2e1a: rootName = solr_34691, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@6f000607 [junit4] 2> 229188 INFO (jetty-closer-3198-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null [junit4] 2> 229189 INFO (closeThreadPool-3204-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72174184860614660-127.0.0.1:34691_solr-n_0000000000) closing [junit4] 2> 229189 INFO (OverseerStateUpdate-72174184860614660-127.0.0.1:34691_solr-n_0000000000) [n:127.0.0.1:34691_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34691_solr [junit4] 2> 229189 INFO (closeThreadPool-3204-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72174184860614660-127.0.0.1:34691_solr-n_0000000000) closing [junit4] 2> 229290 INFO (jetty-closer-3198-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72174184860614660-127.0.0.1:34691_solr-n_0000000000) closing [junit4] 2> 229291 INFO (jetty-closer-3198-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6c2daae4{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0} [junit4] 2> 229292 INFO (jetty-closer-3198-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3621b668{/solr,null,UNAVAILABLE} [junit4] 2> 229292 INFO (jetty-closer-3198-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 229292 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer. [junit4] 2> 229495 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 3 /solr/collections/c2/terms/shard1 [junit4] 2> 3 /solr/collections/c1/terms/shard1 [junit4] 2> 2 /solr/aliases.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 10 /solr/collections/c2/state.json [junit4] 2> 10 /solr/collections/c1/state.json [junit4] 2> 2 /solr/clusterprops.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 21 /solr/overseer/queue [junit4] 2> 8 /solr/overseer/collection-queue-work [junit4] 2> 6 /solr/collections [junit4] 2> 4 /solr/live_nodes [junit4] 2> [junit4] 2> 229498 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:34615 [junit4] 2> 229498 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34615 [junit4] 2> 229498 INFO (SUITE-TestRequestRateLimiter-seed#[B7E8EE7471B77050]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34615 [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J3/temp/solr.servlet.TestRequestRateLimiter_B7E8EE7471B77050-001 [junit4] 2> Aug 10, 2020 6:44:55 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=696, maxMBSortInHeap=7.3569999749500905, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=fr-SN, timezone=Canada/Central [junit4] 2> NOTE: Linux 5.4.0-40-generic amd64/AdoptOpenJDK 13.0.2 (64-bit)/cpus=16,threads=1,free=193652592,total=458752000 [junit4] 2> NOTE: All tests run in this JVM: [CdcrWithNodesRestartsTest, ResponseBuilderTest, FileUtilsTest, TestShortCircuitedRequests, HdfsNNFailoverTest, EmbeddedSolrNoSerializeTest, TestCloudJSONFacetJoinDomain, BasicAuthStandaloneTest, TestBackupRepositoryFactory, CdcrUpdateLogTest, TestNumericRangeQuery64, TestExpandComponent, TestXIncludeConfig, ChaosMonkeySafeLeaderWithPullReplicasTest, TestRestoreCore, TestFieldCache, LargeFieldTest, TestCloudPivotFacet, MetricUtilsTest, ClusterStateUpdateTest, AuthToolTest, TestObjectReleaseTracker, TestDownShardTolerantSearch, ClassificationUpdateProcessorIntegrationTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestTrie, AsyncCallRequestStatusResponseTest, SpellCheckCollatorWithCollapseTest, ParsingFieldUpdateProcessorsTest, RankQueryTest, MetricsHandlerTest, TestTolerantUpdateProcessorRandomCloud, TestFileDictionaryLookup, TestSimpleTextCodec, LeaderFailoverAfterPartitionTest, ConnectionManagerTest, OverseerModifyCollectionTest, TestSystemCollAutoCreate, TestReversedWildcardFilterFactory, TestSmileRequest, TestCloudNestedDocsSort, TestFieldTypeResource, TestSchemaManager, TestExactStatsCache, SchemaApiFailureTest, DistributedFacetExistsSmallTest, CacheHeaderTest, TestRequestRateLimiter] [junit4] Completed [262/864 (1!)] on J3 in 3.30s, 2 tests, 2 failures <<< FAILURES! [...truncated 53931 lines...] [repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/27614/consoleText [repro] Revision: 35771c3cfe955c8631755b52cbb5de480285ded9 [repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" [repro] Repro line: ant test -Dtestcase=TestRequestRateLimiter -Dtests.method=testConcurrentQueries -Dtests.seed=B7E8EE7471B77050 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-SN -Dtests.timezone=Canada/Central -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [repro] Repro line: ant test -Dtestcase=TestRequestRateLimiter -Dtests.method=testSlotBorrowing -Dtests.seed=B7E8EE7471B77050 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-SN -Dtests.timezone=Canada/Central -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [repro] JUnit rest result XML files will be moved to: ./repro-reports [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr/core [repro] TestRequestRateLimiter [repro] ant compile-test [...truncated 2446 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestRequestRateLimiter" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=B7E8EE7471B77050 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-SN -Dtests.timezone=Canada/Central -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [...truncated 86 lines...] [repro] Failures w/original seeds: [repro] 0/5 failed: org.apache.solr.servlet.TestRequestRateLimiter [repro] Exiting with code 0 [...truncated 49 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: builds-unsubscr...@lucene.apache.org For additional commands, e-mail: builds-h...@lucene.apache.org