You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/04/07 10:26:01 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 2016 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/2016/
2 tests failed.
FAILED: org.apache.solr.metrics.reporters.SolrJmxReporterTest.testClosedCore
Error Message:
Captured an uncaught exception in thread: Thread[id=38481, name=Thread-6044, state=RUNNABLE, group=TGRP-SolrJmxReporterTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=38481, name=Thread-6044, state=RUNNABLE, group=TGRP-SolrJmxReporterTest]
at __randomizedtesting.SeedInfo.seed([6EC6CBB4E80D2C2C:19BB1D94C8944EDC]:0)
Caused by: java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([6EC6CBB4E80D2C2C]:0)
at org.junit.Assert.fail(Assert.java:87)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.junit.Assert.assertNotNull(Assert.java:713)
at org.junit.Assert.assertNotNull(Assert.java:723)
at org.apache.solr.metrics.reporters.SolrJmxReporterTest$1.run(SolrJmxReporterTest.java:213)
FAILED: junit.framework.TestSuite.org.apache.solr.update.TestInPlaceUpdateWithRouteField
Error Message:
Timeout occurred while waiting response from server at: https://127.0.0.1:46851/solr
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: https://127.0.0.1:46851/solr
at __randomizedtesting.SeedInfo.seed([6EC6CBB4E80D2C2C]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:692)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:369)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:297)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1171)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:231)
at org.apache.solr.update.TestInPlaceUpdateWithRouteField.setupCluster(TestInPlaceUpdateWithRouteField.java:87)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:475)
at sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:469)
at sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:69)
at sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1228)
at sun.security.ssl.SSLSocketImpl.access$300(SSLSocketImpl.java:75)
at sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:915)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:571)
... 36 more
Build Log:
[...truncated 13898 lines...]
[junit4] Suite: org.apache.solr.update.TestInPlaceUpdateWithRouteField
[junit4] 2> 512055 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 512055 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 512055 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/data-dir-36-001
[junit4] 2> 512055 WARN (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 512056 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 512057 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 512057 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 512058 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001
[junit4] 2> 512058 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 512058 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 512058 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 512059 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 512158 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 39453
[junit4] 2> 512158 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:39453
[junit4] 2> 512158 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:39453
[junit4] 2> 512158 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 39453
[junit4] 2> 512160 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 512163 INFO (zkConnectionManagerCallback-4660-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 512163 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 512166 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 512167 INFO (zkConnectionManagerCallback-4662-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 512167 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 512168 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 512168 INFO (zkConnectionManagerCallback-4664-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 512168 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 512272 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 512272 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 512273 INFO (jetty-launcher-4665-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 512273 INFO (jetty-launcher-4665-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 512273 INFO (jetty-launcher-4665-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 512273 INFO (jetty-launcher-4665-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 512274 INFO (jetty-launcher-4665-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@247bb3e7{/solr,null,AVAILABLE}
[junit4] 2> 512275 INFO (jetty-launcher-4665-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@32138797{SSL, (ssl, http/1.1)}{127.0.0.1:46851}
[junit4] 2> 512275 INFO (jetty-launcher-4665-thread-1) [ ] o.e.j.s.Server Started @512306ms
[junit4] 2> 512275 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:39453/solr, hostPort=46851}
[junit4] 2> 512275 ERROR (jetty-launcher-4665-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 512275 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 512275 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 512275 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 512275 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 512275 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-04-07T09:36:39.628Z
[junit4] 2> 512276 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 512278 INFO (zkConnectionManagerCallback-4667-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 512278 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 512279 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 512284 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 512285 WARN (jetty-launcher-4665-thread-1-SendThread(127.0.0.1:39453)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x104629bd4350003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x104629bd4350003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x104629bd4350003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 513000 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 513000 WARN (jetty-launcher-4665-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 513001 WARN (jetty-launcher-4665-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4283f621[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 513001 WARN (jetty-launcher-4665-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4283f621[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 513007 WARN (jetty-launcher-4665-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 513007 WARN (jetty-launcher-4665-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@fb261f3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 513007 WARN (jetty-launcher-4665-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@fb261f3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 513008 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39453/solr
[junit4] 2> 513009 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 513010 INFO (zkConnectionManagerCallback-4678-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 513010 INFO (jetty-launcher-4665-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 513112 INFO (jetty-launcher-4665-thread-1) [n:127.0.0.1:46851_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 513113 INFO (zkConnectionManagerCallback-4680-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 513113 INFO (jetty-launcher-4665-thread-1) [n:127.0.0.1:46851_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 513167 WARN (jetty-launcher-4665-thread-1) [n:127.0.0.1:46851_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 513172 INFO (jetty-launcher-4665-thread-1) [n:127.0.0.1:46851_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46851_solr
[junit4] 2> 513172 INFO (jetty-launcher-4665-thread-1) [n:127.0.0.1:46851_solr ] o.a.s.c.Overseer Overseer (id=73291915364466693-127.0.0.1:46851_solr-n_0000000000) starting
[junit4] 2> 513179 INFO (OverseerStateUpdate-73291915364466693-127.0.0.1:46851_solr-n_0000000000) [n:127.0.0.1:46851_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:46851_solr
[junit4] 2> 513179 INFO (jetty-launcher-4665-thread-1) [n:127.0.0.1:46851_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46851_solr
[junit4] 2> 513180 INFO (OverseerStateUpdate-73291915364466693-127.0.0.1:46851_solr-n_0000000000) [n:127.0.0.1:46851_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 513182 INFO (jetty-launcher-4665-thread-1) [n:127.0.0.1:46851_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 513183 WARN (jetty-launcher-4665-thread-1) [n:127.0.0.1:46851_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> 513196 INFO (jetty-launcher-4665-thread-1) [n:127.0.0.1:46851_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1
[junit4] 2> 513205 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 513206 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 513207 INFO (zkConnectionManagerCallback-4695-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 513207 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 513208 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 513210 INFO (SUITE-TestInPlaceUpdateWithRouteField-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39453/solr ready
[junit4] 2> 513525 INFO (OverseerThreadFactory-4687-thread-1-processing-n:127.0.0.1:46851_solr) [n:127.0.0.1:46851_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 513532 WARN (OverseerThreadFactory-4687-thread-1-processing-n:127.0.0.1:46851_solr) [n:127.0.0.1:46851_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 2 on collection collection1 is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 513534 INFO (OverseerThreadFactory-4687-thread-1-processing-n:127.0.0.1:46851_solr) [n:127.0.0.1:46851_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46851_solr",
[junit4] 2> "base_url":"https://127.0.0.1:46851/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 513537 INFO (OverseerThreadFactory-4687-thread-1-processing-n:127.0.0.1:46851_solr) [n:127.0.0.1:46851_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:46851_solr",
[junit4] 2> "base_url":"https://127.0.0.1:46851/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 513539 INFO (OverseerThreadFactory-4687-thread-1-processing-n:127.0.0.1:46851_solr) [n:127.0.0.1:46851_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard2_replica_n5",
[junit4] 2> "node_name":"127.0.0.1:46851_solr",
[junit4] 2> "base_url":"https://127.0.0.1:46851/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 513541 INFO (OverseerThreadFactory-4687-thread-1-processing-n:127.0.0.1:46851_solr) [n:127.0.0.1:46851_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard2_replica_n7",
[junit4] 2> "node_name":"127.0.0.1:46851_solr",
[junit4] 2> "base_url":"https://127.0.0.1:46851/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 513547 INFO (OverseerThreadFactory-4687-thread-1-processing-n:127.0.0.1:46851_solr) [n:127.0.0.1:46851_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard3_replica_n9",
[junit4] 2> "node_name":"127.0.0.1:46851_solr",
[junit4] 2> "base_url":"https://127.0.0.1:46851/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard3",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 513549 INFO (OverseerThreadFactory-4687-thread-1-processing-n:127.0.0.1:46851_solr) [n:127.0.0.1:46851_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"collection1_shard3_replica_n11",
[junit4] 2> "node_name":"127.0.0.1:46851_solr",
[junit4] 2> "base_url":"https://127.0.0.1:46851/solr",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard3",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 513574 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=collection1&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-inplace-updates.xml&coreNodeName=core_node2&name=collection1_shard1_replica_n1&action=CREATE&numShards=3&shard=shard1&wt=javabin
[junit4] 2> 513575 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr x:collection1_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 513577 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr x:collection1_shard2_replica_n5 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=collection1&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-inplace-updates.xml&coreNodeName=core_node6&name=collection1_shard2_replica_n5&action=CREATE&numShards=3&shard=shard2&wt=javabin
[junit4] 2> 513577 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr x:collection1_shard3_replica_n9 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=collection1&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-inplace-updates.xml&coreNodeName=core_node10&name=collection1_shard3_replica_n9&action=CREATE&numShards=3&shard=shard3&wt=javabin
[junit4] 2> 513578 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr x:collection1_shard2_replica_n7 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=collection1&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-inplace-updates.xml&coreNodeName=core_node8&name=collection1_shard2_replica_n7&action=CREATE&numShards=3&shard=shard2&wt=javabin
[junit4] 2> 513579 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr x:collection1_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=collection1&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-inplace-updates.xml&coreNodeName=core_node4&name=collection1_shard1_replica_n3&action=CREATE&numShards=3&shard=shard1&wt=javabin
[junit4] 2> 513584 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr x:collection1_shard3_replica_n11 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=collection1&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-inplace-updates.xml&coreNodeName=core_node12&name=collection1_shard3_replica_n11&action=CREATE&numShards=3&shard=shard3&wt=javabin
[junit4] 2> 513602 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 513606 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 513615 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 513617 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.s.IndexSchema Schema name=inplace-updates
[junit4] 2> 513618 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 513618 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n7' using configuration from configset solrCloudCollectionConfig, trusted=true
[junit4] 2> 513618 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 513634 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n7] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard2_replica_n7], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard2_replica_n7/data/]
[junit4] 2> 513643 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8933078366743463]
[junit4] 2> 513644 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.s.IndexSchema Schema name=inplace-updates
[junit4] 2> 513644 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 513644 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n3' using configuration from configset solrCloudCollectionConfig, trusted=true
[junit4] 2> 513645 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n3] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard1_replica_n3], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard1_replica_n3/data/]
[junit4] 2> 513645 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 513646 WARN (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 513648 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.s.IndexSchema Schema name=inplace-updates
[junit4] 2> 513649 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 513649 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n5' using configuration from configset solrCloudCollectionConfig, trusted=true
[junit4] 2> 513649 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n5] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard2_replica_n5], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard2_replica_n5/data/]
[junit4] 2> 513651 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8933078366743463]
[junit4] 2> 513651 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8933078366743463]
[junit4] 2> 513652 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 513670 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=inplace-updates
[junit4] 2> 513670 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 513670 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from configset solrCloudCollectionConfig, trusted=true
[junit4] 2> 513670 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard1_replica_n1/data/]
[junit4] 2> 513671 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.s.IndexSchema Schema name=inplace-updates
[junit4] 2> 513672 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 513672 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard3_replica_n11' using configuration from configset solrCloudCollectionConfig, trusted=true
[junit4] 2> 513672 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.SolrCore [[collection1_shard3_replica_n11] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard3_replica_n11], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard3_replica_n11/data/]
[junit4] 2> 513672 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8933078366743463]
[junit4] 2> 513673 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.s.IndexSchema Schema name=inplace-updates
[junit4] 2> 513673 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 513673 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard3_replica_n9' using configuration from configset solrCloudCollectionConfig, trusted=true
[junit4] 2> 513673 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8933078366743463]
[junit4] 2> 513673 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.SolrCore [[collection1_shard3_replica_n9] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard3_replica_n9], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.update.TestInPlaceUpdateWithRouteField_6EC6CBB4E80D2C2C-001/tempDir-001/node1/collection1_shard3_replica_n9/data/]
[junit4] 2> 513674 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8933078366743463]
[junit4] 2> 513674 WARN (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 513674 WARN (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 513688 WARN (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 513692 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 513692 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 513707 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 513707 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 513708 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 513709 WARN (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 513710 WARN (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 513717 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 513717 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 513718 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 513718 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 513718 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 513719 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 513719 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 513719 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 513720 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 513720 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 513720 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 513720 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
[junit4] 2> 513721 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 513721 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1696373880729370624
[junit4] 2> 513723 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 513723 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 513723 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
[junit4] 2> 513723 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
[junit4] 2> 513723 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 513723 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1696373880731467776
[junit4] 2> 513725 INFO (searcherExecutor-4697-thread-1-processing-n:127.0.0.1:46851_solr x:collection1_shard2_replica_n7 c:collection1 s:shard2 r:core_node8) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.SolrCore [collection1_shard2_replica_n7] Registered new searcher autowarm time: 0 ms
[junit4] 2> 513725 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 513725 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1696373880733564928
[junit4] 2> 513728 INFO (searcherExecutor-4706-thread-1-processing-n:127.0.0.1:46851_solr x:collection1_shard3_replica_n11 c:collection1 s:shard3 r:core_node12) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.SolrCore [collection1_shard3_replica_n11] Registered new searcher autowarm time: 0 ms
[junit4] 2> 513731 INFO (searcherExecutor-4701-thread-1-processing-n:127.0.0.1:46851_solr x:collection1_shard2_replica_n5 c:collection1 s:shard2 r:core_node6) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.SolrCore [collection1_shard2_replica_n5] Registered new searcher autowarm time: 0 ms
[junit4] 2> 513737 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard3 to Terms{values={core_node12=0}, version=0}
[junit4] 2> 513737 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard3
[junit4] 2> 513744 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node8=0}, version=0}
[junit4] 2> 513744 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 513746 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node6=0, core_node8=0}, version=1}
[junit4] 2> 513746 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 513749 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard3: total=2 found=1 timeoute in=9998ms
[junit4] 2> 513751 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 513751 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 513751 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:46851/solr/collection1_shard2_replica_n7/
[junit4] 2> 513751 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_n7 url=https://127.0.0.1:46851/solr START replicas=[https://127.0.0.1:46851/solr/collection1_shard2_replica_n5/] nUpdates=100
[junit4] 2> 513759 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard2_replica_n7 url=https://127.0.0.1:46851/solr DONE. We have no versions. sync failed.
[junit4] 2> 513767 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 513767 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 513768 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 513768 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 513768 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 513771 INFO (qtp954985703-7869) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node6 x:collection1_shard2_replica_n5 ] o.a.s.c.S.Request [collection1_shard2_replica_n5] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 513772 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 513772 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 513772 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 513772 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 513772 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/73291915364466693-core_node8-n_0000000000
[junit4] 2> 513772 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 513772 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 513772 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 513772 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 513772 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 513773 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 513773 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 513773 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 513774 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 513774 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 513774 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig
[junit4] 2> 513775 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
[junit4] 2> 513775 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 513775 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1696373880785993728
[junit4] 2> 513776 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
[junit4] 2> 513776 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 513776 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig
[junit4] 2> 513776 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1696373880787042304
[junit4] 2> 513776 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 513777 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1696373880788090880
[junit4] 2> 513778 INFO (searcherExecutor-4708-thread-1-processing-n:127.0.0.1:46851_solr x:collection1_shard3_replica_n9 c:collection1 s:shard3 r:core_node10) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.SolrCore [collection1_shard3_replica_n9] Registered new searcher autowarm time: 0 ms
[junit4] 2> 513778 INFO (searcherExecutor-4699-thread-1-processing-n:127.0.0.1:46851_solr x:collection1_shard1_replica_n3 c:collection1 s:shard1 r:core_node4) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.SolrCore [collection1_shard1_replica_n3] Registered new searcher autowarm time: 0 ms
[junit4] 2> 513779 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:46851/solr/collection1_shard2_replica_n7/ shard2
[junit4] 2> 513779 INFO (zkCallback-4679-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 513779 INFO (zkCallback-4679-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 513789 INFO (searcherExecutor-4704-thread-1-processing-n:127.0.0.1:46851_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node2) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 513790 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 513790 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 513791 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard3 to Terms{values={core_node10=0, core_node12=0}, version=1}
[junit4] 2> 513791 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard3
[junit4] 2> 513792 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr c:collection1 s:shard2 r:core_node8 x:collection1_shard2_replica_n7 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 513795 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node2=0, core_node4=0}, version=1}
[junit4] 2> 513795 INFO (qtp954985703-7814) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 513796 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 513796 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 513797 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:46851/solr/collection1_shard1_replica_n3/
[junit4] 2> 513797 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica_n3 url=https://127.0.0.1:46851/solr START replicas=[https://127.0.0.1:46851/solr/collection1_shard1_replica_n1/] nUpdates=100
[junit4] 2> 513799 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard1_replica_n3 url=https://127.0.0.1:46851/solr DONE. We have no versions. sync failed.
[junit4] 2> 513801 INFO (zkCallback-4679-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 513801 INFO (qtp954985703-7854) [n:127.0.0.1:46851_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=collection1&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-inplace-updates.xml&coreNodeName=core_node8&name=collection1_shard2_replica_n7&action=CREATE&numShards=3&shard=shard2&wt=javabin} status=0 QTime=223
[junit4] 2> 513803 INFO (qtp954985703-7870) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 513804 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 513804 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 513804 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/73291915364466693-core_node4-n_0000000000
[junit4] 2> 513805 INFO (zkCallback-4679-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 513805 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:46851/solr/collection1_shard1_replica_n3/ shard1
[junit4] 2> 513805 INFO (zkCallback-4679-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 513806 INFO (zkCallback-4679-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 513807 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr c:collection1 s:shard1 r:core_node4 x:collection1_shard1_replica_n3 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 513808 INFO (zkCallback-4679-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 513808 INFO (zkCallback-4679-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 513811 INFO (qtp954985703-7810) [n:127.0.0.1:46851_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=collection1&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-inplace-updates.xml&coreNodeName=core_node4&name=collection1_shard1_replica_n3&action=CREATE&numShards=3&shard=shard1&wt=javabin} status=0 QTime=232
[junit4] 2> 514250 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 514250 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 514250 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:46851/solr/collection1_shard3_replica_n11/
[junit4] 2> 514250 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard3_replica_n11 url=https://127.0.0.1:46851/solr START replicas=[https://127.0.0.1:46851/solr/collection1_shard3_replica_n9/] nUpdates=100
[junit4] 2> 514250 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.u.PeerSync PeerSync: core=collection1_shard3_replica_n11 url=https://127.0.0.1:46851/solr DONE. We have no versions. sync failed.
[junit4] 2> 514251 INFO (qtp954985703-7869) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node10 x:collection1_shard3_replica_n9 ] o.a.s.c.S.Request [collection1_shard3_replica_n9] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 514252 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 514252 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 514252 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard3/leader after winning as /collections/collection1/leader_elect/shard3/election/73291915364466693-core_node12-n_0000000000
[junit4] 2> 514253 INFO (zkCallback-4679-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 514253 INFO (zkCallback-4679-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 514253 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:46851/solr/collection1_shard3_replica_n11/ shard3
[junit4] 2> 514254 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr c:collection1 s:shard3 r:core_node12 x:collection1_shard3_replica_n11 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 514255 INFO (zkCallback-4679-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 514255 INFO (zkCallback-4679-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 514256 INFO (qtp954985703-7855) [n:127.0.0.1:46851_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=collection1&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-inplace-updates.xml&coreNodeName=core_node12&name=collection1_shard3_replica_n11&action=CREATE&numShards=3&shard=shard3&wt=javabin} status=0 QTime=672
[junit4] 2> 514752 INFO (zkCallback-4679-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 514752 INFO (zkCallback-4679-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 514754 INFO (qtp954985703-7811) [n:127.0.0.1:46851_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=collection1&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-inplace-updates.xml&coreNodeName=core_node6&name=collection1_shard2_replica_n5&action=CREATE&numShards=3&shard=shard2&wt=javabin} status=0 QTime=1177
[junit4] 2> 514795 INFO (zkCallback-4679-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 514795 INFO (zkCallback-4679-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [1])
[junit4] 2> 514798 INFO (qtp954985703-7813) [n:127.0.0.1:46851_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=collection1&version=2&replicaType=NRT&property.config=solrconfig-tlog.xml&property.schema=schema-inplace-updates.xml&coreNodeName=core_node10&name=collection1_shard3_replica_n9&action=CREATE&numShards=3&shard=shard3&wt=javabin} status=0 QTime=1221
[junit4] 2> 633671 ERROR (OverseerThreadFactory-4687-thread-1-processing-n:127.0.0.1:46851_solr) [n:127.0.0.1:46851_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Error from shard: https://127.0.0.1:46851/solr
[junit4] 2> => org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: https://127.0.0.1:46851/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:692)
[junit4] 2> org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: https://127.0.0.1:46851/solr
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:692) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1290) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.component.HttpShardHandlerFactory$1.request(HttpShardHandlerFactory.java:169) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.component.ShardRequestor.call(ShardRequestor.java:130) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.component.ShardRequestor.call(ShardRequestor.java:41) ~[java/:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_281]
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_281]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_281]
[junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:180) ~[metrics-core-4.1.5.jar:4.1.5]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_281]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_281]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_281]
[junit4] 2> Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] 2> at java.net.SocketInputStream.socketRead0(Native Method) ~[?:1.8.0_281]
[junit4] 2> at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) ~[?:1.8.0_281]
[junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:171) ~[?:1.8.0_281]
[junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[?:1.8.0_281]
[junit4] 2> at sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:475) ~[?:?]
[junit4] 2> at sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:469) ~[?:?]
[junit4] 2> at sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:69) ~[?:?]
[junit4] 2> at sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1228) ~[?:?]
[junit4] 2> at sun.security.ssl.SSLSocketImpl.access$300(SSLSocketImpl.java:75) ~[?:?]
[junit4] 2> at sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:915) ~[?:?]
[junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) ~[httpcore-4.4.13.jar:4.4.13]
[junit4] 2> at org.apache.solr.util.stats.InstrumentedHttpRequestExecutor.execute(InstrumentedHttpRequestExecutor.java:120) ~[java/:?]
[junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) ~[httpclient-4.5.12.jar:4.5.12]
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:571) ~[java/:?]
[junit4] 2> ... 14 more
[junit4] 2> 693525 ERROR (qtp954985703-7812) [n:127.0.0.1:46851_solr ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: create the collection time out:180s
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.sendToOCPQueue(CollectionsHandler.java:394)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:299)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:257)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:836)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:800)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:545)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1612)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1582)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:766)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:556)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:540)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:395)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:161)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.Channel
[...truncated too long message...]
JmxReporter JMX monitoring for 'SolrJmxReporterTest-kltasm.solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@52786eb2
[junit4] 2> 2090454 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ x:collection1 ] o.a.s.m.SolrMetricReporter Reporter disabled for registry solr.core.collection1
[junit4] 2> 2090472 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=null
[junit4] 2> 2090472 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@25a3cadf: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@65a4ceac
[junit4] 2> 2090485 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@66447290: rootName = SolrJmxReporterTest-ngjttfptt, domain = solr.core.collection1, service url = null, agent id = localhost_1617789768165] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@65a4ceac
[junit4] 2> 2090498 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@22e24604: rootName = SolrJmxReporterTest-cepirdqelk, domain = SolrJmxReporterTest-vwhh, service url = null, agent id = localhost_1617789768165] for registry solr.core.collection1/null
[junit4] 2> 2090498 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@9e9cd19: rootName = SolrJmxReporterTest-kltasm, domain = solr.core.collection1, service url = null, agent id = localhost_1617789768165] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@65a4ceac
[junit4] 2> 2090512 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@41ec8b99
[junit4] 2> 2090513 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2090513 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=867409798
[junit4] 2> 2090513 INFO (coreCloseExecutor-23405-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@41ec8b99
[junit4] 2> 2090513 INFO (coreCloseExecutor-23405-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@41ec8b99
[junit4] 2> 2090513 INFO (coreCloseExecutor-23405-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2090514 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 2090514 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6d6912b7: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@da54e8c
[junit4] 2> 2090520 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 2090520 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@26cbbd4b: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@792a651c
[junit4] 2> 2090523 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 2090523 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@51785cfa: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5b60c18f
[junit4] 2> 2090525 INFO (TEST-SolrJmxReporterTest.testEnabled-seed#[6EC6CBB4E80D2C2C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEnabled
[junit4] 2> 2090526 INFO (SUITE-SolrJmxReporterTest-seed#[6EC6CBB4E80D2C2C]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {}, docValues:{}, maxPointsInLeafNode=788, maxMBSortInHeap=6.666239057978743, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=es-GT, timezone=Etc/GMT-8
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=214840920,total=529006592
[junit4] 2> NOTE: All tests run in this JVM: [RangeFacetCloudTest, TestMergePolicyConfig, TestAtomicUpdateErrorCases, MetricTriggerIntegrationTest, TestJsonFacetsStatsParsing, TestRestoreCore, TestSolrConfigHandler, TestStressVersions, TestSubQueryTransformerDistrib, TestAddFieldRealTimeGet, ClusterStateTest, TestCloudDeduplication, TestExactSharedStatsCache, TestCoreContainer, TestDistributedSearch, TestSolrConfigHandlerCloud, StressHdfsTest, EmbeddedSolrNoSerializeTest, ReplicationFactorTest, VMParamsZkACLAndCredentialsProvidersTest, TermsComponentTest, TestRawTransformer, TestMiniSolrCloudClusterSSL, TestSimComputePlanAction, TestConfigSetsAPIZkFailure, TestUseDocValuesAsStored, TestLegacyField, UpdateParamsTest, TestPullReplicaErrorHandling, HdfsBackupRepositoryIntegrationTest, TestSchemaManager, BasicFunctionalityTest, SampleTest, PolyFieldTest, DistribDocExpirationUpdateProcessorTest, TestCrossCoreJoin, TestInPlaceUpdatesDistrib, TestReplicationHandler, SortByFunctionTest, PeerSyncWithLeaderAndIndexFingerprintCachingTest, RankFieldTest, AutoScalingHandlerTest, ZkDynamicConfigTest, TestInfoStreamLogging, TestPhraseSuggestions, TestSimExtremeIndexing, LeaderElectionIntegrationTest, TestJavabinTupleStreamParser, TestLogWatcher, ChaosMonkeyNothingIsSafeTest, TestBulkSchemaConcurrent, ShardRoutingTest, RequestLoggingTest, AtomicUpdatesTest, DistributedFacetExistsSmallTest, HdfsRecoverLeaseTest, SuggesterWFSTTest, TestNestedUpdateProcessor, TestFieldCache, TestLFUCache, WrapperMergePolicyFactoryTest, TestSolr4Spatial2, TestLegacyBM25SimilarityFactory, AnalyticsQueryTest, TestHashPartitioner, TestMacros, DeleteReplicaTest, HighlighterMaxOffsetTest, TestFieldCacheVsDocValues, DateRangeFieldTest, TestBackupRepositoryFactory, CollectionsAPISolrJTest, SolrCloudReportersTest, DirectoryFactoriesTest, MultiDestinationAuditLoggerTest, CdcrUpdateLogTest, TestGraphTermsQParserPlugin, ClassificationUpdateProcessorTest, OverseerCollectionConfigSetProcessorTest, AsyncCallRequestStatusResponseTest, DistributedTermsComponentTest, TestSortByMinMaxFunction, NestedShardedAtomicUpdateTest, HttpTriggerListenerTest, TestUnifiedSolrHighlighter, BigEndianAscendingWordSerializerTest, TestReversedWildcardFilterFactory, PrimUtilsTest, HdfsBasicDistributedZkTest, SolrCmdDistributorTest, TestFieldSortValues, TestCloudNestedDocsSort, TestFileDictionaryLookup, CdcrVersionReplicationTest, TestEmbeddedSolrServerAdminHandler, ConnectionReuseTest, QueryParsingTest, SearchRateTriggerTest, ZkStateWriterTest, TestSolrCoreProperties, TestSolrJacksonAnnotation, PhrasesIdentificationComponentTest, LoggingHandlerTest, TestSimNodeLostTrigger, DistributedFacetPivotSmallTest, TestDistributedMap, TestSchemaNameResource, TestSystemCollAutoCreate, TaggingAttributeTest, TestJoin, CircularListTest, TestPivotHelperCode, TestFacetMethods, TestSolrCloudWithKerberosAlt, SolrCoreMetricManagerTest, PurgeGraphTest, LeaderFailureAfterFreshStartTest, MetricsHistoryIntegrationTest, MetricsHistoryWithAuthIntegrationTest, MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest, MoveReplicaTest, RecoveryAfterSoftCommitTest, ReplaceNodeNoTargetTest, RouteFieldTest, SSLMigrationTest, SaslZkACLProviderTest, SliceStateTest, SolrCLIZkUtilsTest, SolrXmlInZkTest, SplitShardTest, SystemCollectionCompatTest, TestCloudConsistency, TestRequestForwarding, TestSegmentSorting, TestTlogReplayVsRecovery, TestTlogReplica, CollectionsAPIAsyncDistributedZkTest, HdfsCollectionsAPIDistributedZkTest, SplitByPrefixTest, TestCollectionsAPIViaSolrCloudCluster, NodeAddedTriggerTest, RestoreTriggerStateTest, TriggerSetPropertiesIntegrationTest, TestSimUtils, TestSnapshotCloudManager, CdcrOpsAndBoundariesTest, HdfsSyncSliceTest, HdfsTlogReplayBufferedWhileIndexingTest, HdfsUnloadDistributedZkTest, ByteBuffersDirectoryFactoryTest, CachingDirectoryFactoryTest, ResourceLoaderTest, SOLR749Test, TestConfig, TestConfigSetProperties, TestCustomStream, TestDynamicLoadingUrl, TestDynamicURP, TestSolrCoreSnapshots, TestCSVLoader, TestHdfsBackupRestoreCore, CoreMergeIndexesAdminHandlerTest, DaemonStreamApiTest, PropertiesRequestHandlerTest, SecurityConfHandlerTest, SegmentsInfoRequestHandlerTest, StatsReloadRaceTest, CustomTermsComponentTest, SearchHandlerTest, TestLegacyTerms, TestMultiValuedNumericRangeQuery, TestNumericRangeQuery32, SolrMetricsIntegrationTest, SolrJmxReporterTest]
[junit4] Completed [708/936 (2!)] on J1 in 9.93s, 4 tests, 1 error <<< FAILURES!
[...truncated 51616 lines...]
[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 2017 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/2017/
1 tests failed.
FAILED: org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([272593B0644D4BBA:CFF93E5297A5519A]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:1023)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:983)
at org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming(TestCircuitBreaker.java:245)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.solr.common.SolrException: Circuit Breakers tripped Memory Circuit Breaker triggered as JVM heap usage values are greater than allocated threshold.Seen JVM heap memory usage 427959384 and allocated threshold 395575296
at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:322)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2637)
at org.apache.solr.util.TestHarness.query(TestHarness.java:346)
at org.apache.solr.util.TestHarness.query(TestHarness.java:328)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:997)
... 45 more
Build Log:
[...truncated 16419 lines...]
[junit4] Suite: org.apache.solr.util.TestCircuitBreaker
[junit4] 2> 2408810 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2408811 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.util.TestCircuitBreaker_272593B0644D4BBA-001/data-dir-310-001
[junit4] 2> 2408811 WARN (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 2408811 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2408813 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2408813 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2408813 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 2408818 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2408833 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 2408842 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 2408848 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2409729 WARN (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.c.CoreContainer Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/userfiles]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/userfiles" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/userfiles" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:1.8.0_281]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:886) ~[?:1.8.0_281]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:549) ~[?:1.8.0_281]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_281]
[junit4] 2> at sun.nio.fs.UnixPath.checkWrite(UnixPath.java:801) ~[?:?]
[junit4] 2> at sun.nio.fs.UnixFileSystemProvider.createDirectory(UnixFileSystemProvider.java:376) ~[?:?]
[junit4] 2> at java.nio.file.Files.createDirectory(Files.java:674) ~[?:1.8.0_281]
[junit4] 2> at java.nio.file.Files.createAndCheckIsDirectory(Files.java:781) ~[?:1.8.0_281]
[junit4] 2> at java.nio.file.Files.createDirectories(Files.java:727) ~[?:1.8.0_281]
[junit4] 2> at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:377) [java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:342) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:183) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:156) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:162) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:812) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:802) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:663) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:652) [java/:?]
[junit4] 2> at org.apache.solr.util.TestCircuitBreaker.setUpClass(TestCircuitBreaker.java:61) [test/:?]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_281]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_281]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_281]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_281]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [java/:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_281]
[junit4] 2> 2409730 WARN (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:1.8.0_281]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:886) ~[?:1.8.0_281]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:549) ~[?:1.8.0_281]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:979) ~[?:1.8.0_281]
[junit4] 2> at java.io.File.mkdir(File.java:1311) ~[?:1.8.0_281]
[junit4] 2> at java.io.File.mkdirs(File.java:1343) ~[?:1.8.0_281]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:561) [java/:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:81) [java/:?]
[junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:78) [java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:678) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:184) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:156) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:162) [java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:812) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:802) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:663) [java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:652) [java/:?]
[junit4] 2> at org.apache.solr.util.TestCircuitBreaker.setUpClass(TestCircuitBreaker.java:61) [test/:?]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_281]
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_281]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_281]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_281]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:878) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [java/:?]
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20) [junit-4.13.1.jar:4.13.1]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [randomizedtesting-runner-2.7.2.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_281]
[junit4] 2> 2409731 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2409733 WARN (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@407bcff4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2409733 WARN (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@407bcff4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2409735 WARN (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1a63d868[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2409735 WARN (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1a63d868[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2409737 WARN (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] 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> 2409763 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@39c34af1
[junit4] 2> 2409769 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@39c34af1
[junit4] 2> 2409769 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@39c34af1
[junit4] 2> 2409770 INFO (coreLoadExecutor-23361-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2409777 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2409811 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 2409820 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 2409827 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2409833 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2409833 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@39c34af1
[junit4] 2> 2409833 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.util.TestCircuitBreaker_272593B0644D4BBA-001/data-dir-310-001/]
[junit4] 2> 2409834 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=26, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7172141118984431]
[junit4] 2> 2409898 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2409898 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2409899 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2409900 WARN (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/core/src/test-files/solr/collection1/conf ; switching to use InMemory storage instead.
[junit4] 2> 2409900 INFO (coreLoadExecutor-23361-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2409901 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 2409901 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2409901 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409903 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409903 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409904 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409904 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409904 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409920 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2409920 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 15
[junit4] 2> 2409920 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409921 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409921 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409921 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409922 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409922 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409927 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2409928 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2409928 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409928 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409929 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409929 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409929 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409929 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409935 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2409935 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2409935 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409936 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409936 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409936 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409937 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409937 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409942 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2409942 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2409943 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409943 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409943 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409943 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409944 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409944 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409949 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2409949 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2409950 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409950 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409950 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409951 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409951 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409951 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409956 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2409956 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2409957 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409957 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409957 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409958 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409958 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409958 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409970 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2409971 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 12
[junit4] 2> 2409971 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409971 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409972 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409972 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409972 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409972 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409979 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2409979 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 2409980 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409980 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409981 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409981 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409981 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409982 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409987 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2409987 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2409988 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409988 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409988 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2409988 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2409989 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2409989 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410004 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2410004 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 15
[junit4] 2> 2410005 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410005 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410005 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410005 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410006 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410006 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410011 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2410011 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2410012 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410012 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410012 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410013 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410013 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410013 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410019 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2410019 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2410019 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410019 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410020 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410020 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410020 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410021 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410027 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2410027 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 2410027 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410028 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410028 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410028 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410028 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410029 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410034 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2410034 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2410034 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410035 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410035 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410035 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410035 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410036 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410041 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2410041 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2410042 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410042 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410042 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410042 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410043 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410043 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410048 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2410048 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2410049 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410049 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410049 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410049 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410050 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410050 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410055 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2410056 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2410056 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410056 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410057 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410057 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410057 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410058 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410065 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2410065 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 2410066 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410066 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410066 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410066 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410067 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410067 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410073 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2410074 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 6
[junit4] 2> 2410074 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410074 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410075 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410075 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 2410075 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2410075 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2410081 INFO (searcherExecutor-23363-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2410081 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 2410085 INFO (TEST-TestCircuitBreaker.testResponseWithCBTiming-seed#[272593B0644D4BBA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testResponseWithCBTiming
[junit4] 2> 2410085 INFO (TEST-TestCircuitBreaker.testResponseWithCBTiming-seed#[272593B0644D4BBA]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml&debugQuery=true} status=503 QTime=0
[junit4] 2> 2410085 ERROR (TEST-TestCircuitBreaker.testResponseWithCBTiming-seed#[272593B0644D4BBA]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=*:*&wt=xml&debugQuery=true:org.apache.solr.common.SolrException: Circuit Breakers tripped Memory Circuit Breaker triggered as JVM heap usage values are greater than allocated threshold.Seen JVM heap memory usage 427959384 and allocated threshold 395575296
[junit4] 2>
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:322)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2637)
[junit4] 2> at org.apache.solr.util.TestHarness.query(TestHarness.java:346)
[junit4] 2> at org.apache.solr.util.TestHarness.query(TestHarness.java:328)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:997)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:983)
[junit4] 2> at org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming(TestCircuitBreaker.java:245)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at org.junit.rules.RunRules.evaluate(RunRules.java:20)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 2410086 INFO (TEST-TestCircuitBreaker.testResponseWithCBTiming-seed#[272593B0644D4BBA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testResponseWithCBTiming
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCircuitBreaker -Dtests.method=testResponseWithCBTiming -Dtests.seed=272593B0644D4BBA -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ru-RU -Dtests.timezone=America/Denver -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.01s J3 | TestCircuitBreaker.testResponseWithCBTiming <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([272593B0644D4BBA:CFF93E5297A5519A]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:1023)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:983)
[junit4] > at org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming(TestCircuitBreaker.java:245)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: org.apache.solr.common.SolrException: Circuit Breakers tripped Memory Circuit Breaker triggered as JVM heap usage values are greater than allocated threshold.Seen JVM heap memory usage 427959384 and allocated threshold 395575296
[junit4] > at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:322)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2637)
[junit4] > at org.apache.solr.util.TestHarness.query(TestHarness.java:346)
[junit4] > at org.apache.solr.util.TestHarness.query(TestHarness.java:328)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:997)
[junit4] > ... 45 more
[junit4] 2> 2410091 INFO (TEST-TestCircuitBreaker.testFakeCPUCircuitBreaker-seed#[272593B0644D4BBA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testFakeCPUCircuitBreaker
[junit4] 2> 2410095 INFO (TestCircuitBreaker-23367-thread-2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
[junit4] 2> 2410095 INFO (TestCircuitBreaker-23367-thread-1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
[junit4] 2> 2410095 INFO (TestCircuitBreaker-23367-thread-1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
[junit4] 2> 2410095 INFO (TestCircuitBreaker-23367-thread-2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
[junit4] 2> 2410095 INFO (TestCircuitBreaker-23367-thread-3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
[junit4] 2> 2410096 INFO (TEST-TestCircuitBreaker.testFakeCPUCircuitBreaker-seed#[272593B0644D4BBA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testFakeCPUCircuitBreaker
[junit4] 2> 2410099 INFO (TEST-TestCircuitBreaker.testCBAlwaysTrips-seed#[272593B0644D4BBA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCBAlwaysTrips
[junit4] 2> 2410100 INFO (TEST-TestCircuitBreaker.testCBAlwaysTrips-seed#[272593B0644D4BBA]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
[junit4] 2> 2410100 INFO (TEST-TestCircuitBreaker.testCBAlwaysTrips-seed#[272593B0644D4BBA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCBAlwaysTrips
[junit4] 2> 2410104 INFO (TEST-TestCircuitBreaker.testBuildingMemoryPressure-seed#[272593B0644D4BBA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBuildingMemoryPressure
[junit4] 2> 2410107 INFO (TestCircuitBreaker-23368-thread-1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 2410111 INFO (TestCircuitBreaker-23368-thread-1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 2410111 INFO (TestCircuitBreaker-23368-thread-3) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 2410111 INFO (TestCircuitBreaker-23368-thread-2) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 2410118 INFO (TestCircuitBreaker-23368-thread-4) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
[junit4] 2> 2410120 INFO (TEST-TestCircuitBreaker.testBuildingMemoryPressure-seed#[272593B0644D4BBA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBuildingMemoryPressure
[junit4] 2> 2410123 INFO (TEST-TestCircuitBreaker.testCBFakeMemoryPressure-seed#[272593B0644D4BBA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCBFakeMemoryPressure
[junit4] 2> 2410124 INFO (TEST-TestCircuitBreaker.testCBFakeMemoryPressure-seed#[272593B0644D4BBA]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
[junit4] 2> 2410125 INFO (TEST-TestCircuitBreaker.testCBFakeMemoryPressure-seed#[272593B0644D4BBA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCBFakeMemoryPressure
[junit4] 2> 2410125 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2410125 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=292997314
[junit4] 2> 2410126 INFO (coreCloseExecutor-23370-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@78a5000d
[junit4] 2> 2410127 INFO (coreCloseExecutor-23370-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@78a5000d
[junit4] 2> 2410127 INFO (coreCloseExecutor-23370-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2a050bef: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@35168100
[junit4] 2> 2410161 INFO (coreCloseExecutor-23370-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 2410168 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 2410168 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@38a4677d: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@3880eff7
[junit4] 2> 2410173 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 2410173 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4a4668ce: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@6e8f786d
[junit4] 2> 2410175 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 2410175 INFO (SUITE-TestCircuitBreaker-seed#[272593B0644D4BBA]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5252effc: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5ed57dde
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.util.TestCircuitBreaker_272593B0644D4BBA-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {title_stringNoNorms=PostingsFormat(name=LuceneVarGapDocFreqInterval), title_stemmed=PostingsFormat(name=Asserting), multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), inStock_b1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), _root_=PostingsFormat(name=Asserting), name=FST50, id=FST50, text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), title=PostingsFormat(name=Asserting), title_lettertok=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene80), intDefault=DocValuesFormat(name=Direct), id_i1=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene80), intDvoDefault=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Lucene80), range_facet_l=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1096, maxMBSortInHeap=6.078199299613749, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ru-RU, timezone=America/Denver
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=71251696,total=527433728
[junit4] 2> NOTE: All tests run in this JVM: [TestScoreJoinQPNoScore, V2StandaloneTest, CurrencyFieldTypeTest, TestManagedSchema, TestComponentsName, ZkCollectionPropsCachingTest, CursorMarkTest, TestStressIncrementalBackup, BadIndexSchemaTest, DocValuesMultiTest, TestExpandComponent, TestXmlQParserPlugin, TestJsonRangeFacets, AutoAddReplicasIntegrationTest, URLClassifyProcessorTest, ConfigureRecoveryStrategyTest, TestDeleteCollectionOnDownNodes, TestSort, MBeansHandlerTest, TestTlogReplica, HdfsUnloadDistributedZkTest, DistributedQueueTest, CrossCollectionJoinQueryTest, NodeMarkersRegistrationTest, TestLRUStatsCache, TestDefaultStatsCache, NumericFieldsTest, TestCloudPhrasesIdentificationComponent, TestAuthenticationFramework, RAMDirectoryFactoryTest, RootFieldTest, AddReplicaTest, TestInPlaceUpdatesStandalone, TestCaffeineCache, TestFieldCacheSort, TestDocTermOrds, DirectUpdateHandlerTest, TestCodecSupport, TriggerSetPropertiesIntegrationTest, StatelessScriptUpdateProcessorFactoryTest, SolrTestCaseJ4DeleteCoreTest, NumberUtilsTest, TestSolrQueryResponse, AddSchemaFieldsUpdateProcessorFactoryTest, TestAnalyzedSuggestions, SimpleFacetsTest, MergeStrategyTest, DistributedSpellCheckComponentTest, SolrJmxReporterTest, TestSSLRandomization, InfixSuggestersTest, DateMathParserTest, TestPerFieldSimilarity, TestPolicyCloud, UUIDFieldTest, OverseerTaskQueueTest, BJQParserTest, TestBlobHandler, HttpSolrCallGetCoreTest, RuleEngineTest, FastVectorHighlighterTest, TestNamedUpdateProcessors, XMLAtomicUpdateMultivalueTest, CursorPagingTest, TestGroupingSearch, SolrCoreCheckLockOnStartupTest, TestWriterPerf, TestSafeXMLParsing, HDFSCollectionsAPITest, TestSolrDeletionPolicy2, TestTrie, TriLevelCompositeIdRoutingTest, TestSimLargeCluster, CdcrReplicationHandlerTest, TestHdfsCloudBackupRestore, TestSkipOverseerOperations, RankQueryTest, TestRealTimeGet, ForceLeaderWithTlogReplicasTest, ExplicitHLLTest, DistributedDebugComponentTest, TestDynamicFieldCollectionResource, TestSlowCompositeReaderWrapper, TestRTGBase, TestGeoJSONResponseWriter, DistributedMLTComponentTest, TestHdfsBackupRestoreCore, ScheduledTriggerTest, PreAnalyzedFieldTest, TestDynamicFieldNamesIndexCorrectly, ConcurrentCreateCollectionTest, SecurityConfHandlerTest, ZkSolrClientTest, TestConfigSetsAPIExclusivity, ExecutePlanActionTest, CloudReplicaSourceTest, TestFieldTypeCollectionResource, TestSolrCloudSnapshots, BasicDistributedZkTest, JWTIssuerConfigTest, TestJsonFacetRefinement, TestDistributedGrouping, HttpPartitionWithTlogReplicasTest, PeerSyncWithBufferUpdatesTest, TestFuzzyAnalyzedSuggestions, SolrIndexMetricsTest, IndexBasedSpellCheckerTest, MetricsConfigTest, SolrLogPostToolTest, SynonymTokenizerTest, ExitableDirectoryReaderTest, TestCorePropertiesReload, TestManagedStopFilterFactory, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, BasicZkTest, ChaosMonkeyNothingIsSafeTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, CloudExitableDirectoryReaderTest, ClusterStateMockUtilTest, ClusterStateTest, ClusterStateUpdateTest, CollectionStateFormat2Test, CollectionsAPISolrJTest, MissingSegmentRecoveryTest, MultiSolrCloudTestCaseTest, MultiThreadedOCPTest, RecoveryAfterSoftCommitTest, ReplicationFactorTest, ShardTermsTest, SolrCLIZkUtilsTest, SolrXmlInZkTest, TestCloudPivotFacet, TestLeaderElectionWithEmptyReplica, TestPullReplica, TestPullReplicaErrorHandling, TestTolerantUpdateProcessorRandomCloud, TestWaitForStateWithJettyShutdowns, VMParamsZkACLAndCredentialsProvidersTest, AssignTest, AsyncCallRequestStatusResponseTest, BackupRestoreApiErrorConditionsTest, ConcurrentDeleteAndCreateCollectionTest, HdfsCloudIncrementalBackupTest, HdfsAutoAddReplicasIntegrationTest, HttpTriggerListenerTest, IndexSizeTriggerTest, MetricTriggerIntegrationTest, MetricTriggerTest, TestSimClusterStateProvider, TestSimComputePlanAction, TestSimDistribStateManager, TestSimNodeAddedTrigger, TestSimNodeLostTrigger, TestSimPolicyCloud, TestSimScenario, TestSimUtils, TestSnapshotCloudManager, HdfsNNFailoverTest, HdfsRecoveryZkTest, HdfsRestartWhileUpdatingTest, RulesTest, TestConfig, TestConfigSetProperties, TestCustomStream, TestDynamicURP, TestInitParams, TestLazyCores, BinaryUpdateRequestHandlerTest, CSVRequestHandlerTest, FieldAnalysisRequestHandlerTest, JsonLoaderTest, TestReplicationHandler, SolrRrdBackendFactoryTest, SubstringBytesRefFilterTest, TestFacetMethods, TestFaceting, TestMacros, SmileWriterTest, TestJavabinTupleStreamParser, TestPushWriter, TestChildDocTransformer, TestSubQueryTransformer, TestSubQueryTransformerDistrib, ExternalFileFieldSortTest, IndexSchemaRuntimeFieldTest, PrimitiveFieldTypeTest, RankFieldTest, TestBinaryField, TestBulkSchemaConcurrent, TestSchemaField, TestSchemalessBufferedUpdates, ReturnFieldsTest, SignificantTermsQParserPluginTest, SolrIndexSearcherTest, TestDocSet, TestElisionMultitermQuery, TestExtendedDismaxParser, TestFoldingMultitermQuery, TestHashQParserPlugin, TestInitQParser, TestLRUCache, TestLegacyNumericRangeQueryBuilder, TestMaxScoreQueryParser, TestMinHashQParser, TestMissingGroups, TestNoOpRegenerator, TestPayloadScoreQParserPlugin, TestReload, TestReloadDeadlock, TestSearchPerf, TestSearcherReuse, TestSimpleQParserPlugin, TestSmileRequest, TestSolr4Spatial, TestSolr4Spatial2, TestStandardQParsers, TestStressLucene, TestStressUserVersions, TestStressVersions, DistributedFacetSimpleRefinementLongTailTest, SpatialHeatmapFacetsTest, MultiDestinationAuditLoggerTest, PKIAuthenticationIntegrationTest, TestExternalRoleRuleBasedAuthorizationPlugin, TestPKIAuthenticationPlugin, TestImpersonationWithHadoopAuth, TestRuleBasedAuthorizationWithKerberos, TestSuggestSpellingConverter, SuggesterTSTTest, BlockDirectoryTest, TestUninvertingReader, SolrIndexSplitterTest, TestIndexingPerformance, TestUpdate, AtomicUpdatesTest, ClassificationUpdateProcessorIntegrationTest, CloneFieldUpdateProcessorFactoryTest, DimensionalRoutedAliasUpdateProcessorTest, DocExpirationUpdateProcessorFactoryTest, TestCircuitBreaker]
[junit4] Completed [923/936 (1!)] on J3 in 1.51s, 5 tests, 1 error <<< FAILURES!
[...truncated 50847 lines...]