You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/03/19 16:03:43 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (64bit/jdk-15-ea+14) - Build
# 26057 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/26057/
Java: 64bit/jdk-15-ea+14 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.client.ref_guide_examples.JsonRequestApiTest.testStatFacet1
Error Message:
expected: java.lang.Integer<3> but was: java.lang.Long<3>
Stack Trace:
java.lang.AssertionError: expected: java.lang.Integer<3> but was: java.lang.Long<3>
at __randomizedtesting.SeedInfo.seed([54F18BFF063FEA0:1FFF59C37A8E748A]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:118)
at org.junit.Assert.assertEquals(Assert.java:144)
at org.apache.solr.client.ref_guide_examples.JsonRequestApiTest.testStatFacet1(JsonRequestApiTest.java:470)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.client.ref_guide_examples.JsonRequestApiTest.testStatFacet1
Error Message:
expected: java.lang.Integer<3> but was: java.lang.Long<3>
Stack Trace:
java.lang.AssertionError: expected: java.lang.Integer<3> but was: java.lang.Long<3>
at __randomizedtesting.SeedInfo.seed([54F18BFF063FEA0:1FFF59C37A8E748A]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:118)
at org.junit.Assert.assertEquals(Assert.java:144)
at org.apache.solr.client.ref_guide_examples.JsonRequestApiTest.testStatFacet1(JsonRequestApiTest.java:470)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 16611 lines...]
[junit4] Suite: org.apache.solr.client.ref_guide_examples.JsonRequestApiTest
[junit4] 2> 29967 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 29967 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 29968 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.ref_guide_examples.JsonRequestApiTest_54F18BFF063FEA0-001/data-dir-5-001
[junit4] 2> 29969 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 29969 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 29972 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.ref_guide_examples.JsonRequestApiTest_54F18BFF063FEA0-001/tempDir-001
[junit4] 2> 29972 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 29972 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 29972 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 30072 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:34973
[junit4] 2> 30072 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:34973
[junit4] 2> 30072 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34973
[junit4] 2> 30072 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34973
[junit4] 2> 30074 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 30076 INFO (zkConnectionManagerCallback-170-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 30076 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 30078 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 30080 INFO (zkConnectionManagerCallback-172-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 30080 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 30083 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 30085 INFO (zkConnectionManagerCallback-174-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 30085 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 30195 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 30195 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 30195 INFO (jetty-launcher-175-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+14-561
[junit4] 2> 30195 INFO (jetty-launcher-175-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 30195 INFO (jetty-launcher-175-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 30196 INFO (jetty-launcher-175-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 30196 INFO (jetty-launcher-175-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3aecd4c5{/solr,null,AVAILABLE}
[junit4] 2> 30197 INFO (jetty-launcher-175-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@199a5bde{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:44613}
[junit4] 2> 30197 INFO (jetty-launcher-175-thread-1) [ ] o.e.j.s.Server Started @30211ms
[junit4] 2> 30197 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44613}
[junit4] 2> 30197 ERROR (jetty-launcher-175-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 30197 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 30197 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 30197 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 30197 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
[junit4] 2> 30197 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-03-19T15:57:29.061896Z
[junit4] 2> 30198 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 30200 INFO (zkConnectionManagerCallback-177-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 30200 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 30200 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 30308 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 30310 WARN (jetty-launcher-175-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@23830743[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 30310 WARN (jetty-launcher-175-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@23830743[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 30313 WARN (jetty-launcher-175-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@34663402[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 30313 WARN (jetty-launcher-175-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@34663402[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 30314 INFO (jetty-launcher-175-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34973/solr
[junit4] 2> 30314 INFO (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 30316 INFO (zkConnectionManagerCallback-184-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 30316 INFO (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 30357 INFO (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44613_solr
[junit4] 2> 30357 INFO (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_solr ] o.a.s.c.Overseer Overseer (id=72074528482263044-127.0.0.1:44613_solr-n_0000000000) starting
[junit4] 2> 30360 INFO (OverseerStateUpdate-72074528482263044-127.0.0.1:44613_solr-n_0000000000) [n:127.0.0.1:44613_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:44613_solr
[junit4] 2> 30360 INFO (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44613_solr
[junit4] 2> 30360 INFO (OverseerStateUpdate-72074528482263044-127.0.0.1:44613_solr-n_0000000000) [n:127.0.0.1:44613_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 30361 INFO (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 30362 WARN (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_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> 30371 INFO (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 30385 INFO (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44613.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d5860b3
[junit4] 2> 30393 INFO (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44613.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d5860b3
[junit4] 2> 30394 INFO (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44613.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d5860b3
[junit4] 2> 30395 INFO (jetty-launcher-175-thread-1) [n:127.0.0.1:44613_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.ref_guide_examples.JsonRequestApiTest_54F18BFF063FEA0-001/tempDir-001/node1/.
[junit4] 2> 30411 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 30411 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 30412 INFO (zkConnectionManagerCallback-193-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 30413 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 30413 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 30414 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34973/solr ready
[junit4] 2> 30512 INFO (qtp1449178457-603) [n:127.0.0.1:44613_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=techproducts_config&name=techproducts&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 30521 INFO (OverseerThreadFactory-83-thread-1-processing-n:127.0.0.1:44613_solr) [n:127.0.0.1:44613_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection techproducts
[junit4] 2> 30631 INFO (OverseerStateUpdate-72074528482263044-127.0.0.1:44613_solr-n_0000000000) [n:127.0.0.1:44613_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"techproducts",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"techproducts_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:44613/solr",
[junit4] 2> "node_name":"127.0.0.1:44613_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 30846 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=techproducts_config&newCollection=true&name=techproducts_shard1_replica_n1&action=CREATE&numShards=1&collection=techproducts&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 30847 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 30859 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 30859 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 30860 WARN (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/extraction/lib
[junit4] 2> 30860 WARN (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/dist
[junit4] 2> 30860 WARN (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/clustering/lib
[junit4] 2> 30860 WARN (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/dist
[junit4] 2> 30860 WARN (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/langid/lib
[junit4] 2> 30860 WARN (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/dist
[junit4] 2> 30860 WARN (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/dist filtered by solr-ltr-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/dist
[junit4] 2> 30860 WARN (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/contrib/velocity/lib
[junit4] 2> 30860 WARN (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/dist
[junit4] 2> 30860 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 30865 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.SolrIndexConfig IndexWriter infoStream solr logging is enabled
[junit4] 2> 30884 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 31035 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.RestManager Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path /schema/analysis/stopwords/english
[junit4] 2> 31035 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.RestManager Registered ManagedResource impl org.apache.solr.rest.schema.analysis.ManagedSynonymGraphFilterFactory$SynonymManager for path /schema/analysis/synonyms/english
[junit4] 2> 31035 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.RestManager Added observer of type org.apache.solr.rest.schema.analysis.ManagedStopFilterFactory to existing ManagedResource /schema/analysis/stopwords/english
[junit4] 2> 31035 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.RestManager Added observer of type org.apache.solr.rest.schema.analysis.ManagedSynonymGraphFilterFactory to existing ManagedResource /schema/analysis/synonyms/english
[junit4] 2> 31037 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 31041 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'techproducts_shard1_replica_n1' using configuration from configset techproducts_config, trusted=true
[junit4] 2> 31041 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44613.solr.core.techproducts.shard1.replica_n1' (registry 'solr.core.techproducts.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5d5860b3
[junit4] 2> 31041 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrCore [[techproducts_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.ref_guide_examples.JsonRequestApiTest_54F18BFF063FEA0-001/tempDir-001/node1/./techproducts_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.ref_guide_examples.JsonRequestApiTest_54F18BFF063FEA0-001/tempDir-001/node1/./techproducts_shard1_replica_n1/data/]
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-605]: init: current segments file is "segments"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@600c21c9
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-605]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-605]: 0 msec to checkpoint
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: init: create=true reader=null
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]:
[junit4] 2> dir=MockDirectoryWrapper(ByteBuffersDirectory@32a495be lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33a7e84a)
[junit4] 2> index=
[junit4] 2> version=9.0.0
[junit4] 2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
[junit4] 2> ramBufferSizeMB=100.0
[junit4] 2> maxBufferedDocs=-1
[junit4] 2> mergedSegmentWarmer=null
[junit4] 2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
[junit4] 2> commit=null
[junit4] 2> openMode=CREATE
[junit4] 2> similarity=org.apache.solr.search.similarities.SchemaSimilarityFactory$SchemaSimilarity
[junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
[junit4] 2> codec=Lucene84
[junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream
[junit4] 2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1, deletesPctAllowed=33.0
[junit4] 2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@69726e4a
[junit4] 2> readerPooling=true
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> commitOnClose=true
[junit4] 2> indexSort=null
[junit4] 2> checkPendingFlushOnUpdate=true
[junit4] 2> softDeletesField=null
[junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@1397ed58
[junit4] 2>
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: MMapDirectory.UNMAP_SUPPORTED=true
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: now flush at close
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: start flush: applyAllDeletes=true
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: index before flush
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-605]: startFullFlush
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-605]: qtp1449178457-605 finishFullFlush success=true
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [BD][qtp1449178457-605]: waitApply: no deletes to apply
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MS][qtp1449178457-605]: initDynamicDefaults spins=false maxThreadCount=1 maxMergeCount=6
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MS][qtp1449178457-605]: updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
[junit4] 2> 31043 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MS][qtp1449178457-605]: now merge
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MS][qtp1449178457-605]: index:
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MS][qtp1449178457-605]: no more merges pending; now return
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: waitForMerges
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: waitForMerges done
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: commit: start
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: commit: enter lock
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: commit: now prepare
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: prepareCommit: flush
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: index before flush
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-605]: startFullFlush
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [BD][qtp1449178457-605]: waitApply: no deletes to apply
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-605]: qtp1449178457-605 finishFullFlush success=true
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: startCommit(): start
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: startCommit index= changeCount=2
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: startCommit: wrote pending segments file "pending_segments_1"
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: done all syncs: []
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: commit: pendingCommit != null
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: commit: done writing segments file "segments_1"
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-605]: now checkpoint "" [0 segments ; isCommit = true]
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-605]: 0 msec to checkpoint
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: commit: took 0.2 msec
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: commit: done
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: rollback
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: all running merges have aborted
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: rollback: done finish merges
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-605]: abort
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-605]: done abort success=true
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: rollback: infos=
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-605]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-605]: 0 msec to checkpoint
[junit4] 2> 31044 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 31071 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 31071 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 31072 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 31072 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-605]: init: current segments file is "segments_1"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@600c21c9
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-605]: init: load commit "segments_1"
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-605]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-605]: 0 msec to checkpoint
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: init: create=false reader=null
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]:
[junit4] 2> dir=MockDirectoryWrapper(ByteBuffersDirectory@32a495be lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@33a7e84a)
[junit4] 2> index=
[junit4] 2> version=9.0.0
[junit4] 2> analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
[junit4] 2> ramBufferSizeMB=100.0
[junit4] 2> maxBufferedDocs=-1
[junit4] 2> mergedSegmentWarmer=null
[junit4] 2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
[junit4] 2> commit=null
[junit4] 2> openMode=APPEND
[junit4] 2> similarity=org.apache.solr.search.similarities.SchemaSimilarityFactory$SchemaSimilarity
[junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1, maxMergeCount=-1, ioThrottle=true
[junit4] 2> codec=Lucene84
[junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream
[junit4] 2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1, deletesPctAllowed=33.0
[junit4] 2> indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@6bf7cc69
[junit4] 2> readerPooling=true
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> commitOnClose=true
[junit4] 2> indexSort=null
[junit4] 2> checkPendingFlushOnUpdate=true
[junit4] 2> softDeletesField=null
[junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@37a1e340
[junit4] 2>
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: MMapDirectory.UNMAP_SUPPORTED=true
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: flush at getReader
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-605]: startFullFlush
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [BD][qtp1449178457-605]: waitApply: no deletes to apply
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: incRefDeleter for NRT reader version=2 segments=
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt)
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-605]: qtp1449178457-605 finishFullFlush success=true
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-605]: getReader took 0 msec
[junit4] 2> 31073 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@21d5a211[techproducts_shard1_replica_n1] main]
[junit4] 2> 31075 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/techproducts_config
[junit4] 2> 31075 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/techproducts_config
[junit4] 2> 31075 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.ManagedResource Loaded initArgs {} for /rest/managed
[junit4] 2> 31076 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_stopwords_english.json using ZooKeeperStorageIO:path=/configs/techproducts_config
[junit4] 2> 31076 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true} for /schema/analysis/stopwords/english
[junit4] 2> 31076 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.s.a.ManagedWordSetResource Loaded 35 words for /schema/analysis/stopwords/english
[junit4] 2> 31076 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.ManagedResource Notified 2 observers of /schema/analysis/stopwords/english
[junit4] 2> 31076 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path _schema_analysis_synonyms_english.json using ZooKeeperStorageIO:path=/configs/techproducts_config
[junit4] 2> 31076 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true, format=solr} for /schema/analysis/synonyms/english
[junit4] 2> 31076 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.s.a.ManagedSynonymGraphFilterFactory Loaded 3 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 31076 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.r.ManagedResource Notified 2 observers of /schema/analysis/synonyms/english
[junit4] 2> 31077 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/techproducts_config/managed-schema
[junit4] 2> 31078 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 31078 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 31078 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default, field=text, classname=solr.DirectSolrSpellChecker, distanceMeasure=internal, accuracy=0.5, maxEdits=2, minPrefix=1, maxInspections=5, minQueryLength=4, maxQueryFrequency=0.01}
[junit4] 2> 31079 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 31079 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.s.s.SolrSuggester init: {name=mySuggester, lookupImpl=FuzzyLookupFactory, dictionaryImpl=DocumentDictionaryFactory, field=cat, weightField=price, suggestAnalyzerFieldType=string, buildOnStartup=false}
[junit4] 2> 31079 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=mySuggester, lookupImpl=FuzzyLookupFactory, dictionaryImpl=DocumentDictionaryFactory, field=cat, weightField=price, suggestAnalyzerFieldType=string, buildOnStartup=false}
[junit4] 2> 31080 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 31081 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1661608604409528320
[junit4] 2> 31083 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 c:techproducts s:shard1 r:core_node2) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@21d5a211[techproducts_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 31084 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 c:techproducts s:shard1 r:core_node2) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.S.Request [techproducts_shard1_replica_n1] webapp=null path=null params={q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false&event=firstSearcher} hits=0 status=0 QTime=0
[junit4] 2> 31084 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 c:techproducts s:shard1 r:core_node2) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 31084 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 c:techproducts s:shard1 r:core_node2) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 31084 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 c:techproducts s:shard1 r:core_node2) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 31084 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 c:techproducts s:shard1 r:core_node2) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrCore [techproducts_shard1_replica_n1] Registered new searcher Searcher@21d5a211[techproducts_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 31087 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/techproducts/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 31090 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/techproducts/leaders/shard1
[junit4] 2> 31093 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 31093 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 31093 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:44613/solr/techproducts_shard1_replica_n1/
[junit4] 2> 31094 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 31094 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:44613/solr/techproducts_shard1_replica_n1/ has no replicas
[junit4] 2> 31095 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/techproducts/leaders/shard1/leader after winning as /collections/techproducts/leader_elect/shard1/election/72074528482263044-core_node2-n_0000000000
[junit4] 2> 31097 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:44613/solr/techproducts_shard1_replica_n1/ shard1
[junit4] 2> 31198 INFO (zkCallback-183-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/techproducts/state.json] for collection [techproducts] has occurred - updating... (live nodes size: [1])
[junit4] 2> 31198 INFO (zkCallback-183-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/techproducts/state.json] for collection [techproducts] has occurred - updating... (live nodes size: [1])
[junit4] 2> 31199 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 31201 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=techproducts_config&newCollection=true&name=techproducts_shard1_replica_n1&action=CREATE&numShards=1&collection=techproducts&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=355
[junit4] 2> 31203 INFO (qtp1449178457-603) [n:127.0.0.1:44613_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 31300 INFO (zkCallback-183-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/techproducts/state.json] for collection [techproducts] has occurred - updating... (live nodes size: [1])
[junit4] 2> 31300 INFO (zkCallback-183-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/techproducts/state.json] for collection [techproducts] has occurred - updating... (live nodes size: [1])
[junit4] 2> 31300 INFO (zkCallback-183-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/techproducts/state.json] for collection [techproducts] has occurred - updating... (live nodes size: [1])
[junit4] 2> 31301 INFO (qtp1449178457-603) [n:127.0.0.1:44613_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=techproducts_config&name=techproducts&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=789
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1661608604674818048,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@37a1e340 commitCommandVersion:1661608604674818048
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: commit: start
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: commit: enter lock
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: commit: now prepare
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: prepareCommit: flush
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: index before flush
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-609]: startFullFlush
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-609]: anyChanges? numDocsInRam=32 deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DWFC][qtp1449178457-609]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0 32 deleted terms (unique count=32) bytesUsed=5742, segment=_0, aborted=false, numDocsInRAM=32, deleteQueue=DWDQ: [ generation: 1 ]]
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [BD][qtp1449178457-609]: compressed 5742 to 1040 bytes (18.11%) for deletes/updates; private segment null
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1449178457-609]: flush postings as segment _0 numDocs=32
[junit4] 2> 31334 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: 0 msec to write norms
[junit4] 2> 31335 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: 0 msec to write docValues
[junit4] 2> 31336 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: 0 msec to write points
[junit4] 2> 31337 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: 0 msec to finish stored fields
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: 5 msec to write postings and finish vectors
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: 0 msec to write fieldInfos
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1449178457-609]: new segment has 0 deleted docs
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1449178457-609]: new segment has 0 soft-deleted docs
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1449178457-609]: new segment has vectors; norms; docValues; prox; freqs
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1449178457-609]: flushedFiles=[_0_Lucene84_0.pos, _0.fdm, _0_Lucene84_0.doc, _0_Lucene84_0.tim, _0.nvd, _0_Lucene84_0.tip, _0.fdx, _0.fdt, _0.tvx, _0_Lucene80_0.dvm, _0.dii, _0.tvd, _0_Lucene80_0.dvd, _0_Lucene84_0.pay, _0.tvm, _0.dim, _0.nvm, _0.fnm]
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1449178457-609]: flushed codec=Lucene84
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1449178457-609]: flushed: segment=_0 ramUsed=0.551 MB newFlushedSize=0.024 MB docs/MB=1,334.331
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DWPT][qtp1449178457-609]: flush time 9.087286 msec
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: publishFlushedSegment seg-private updates=null
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: publishFlushedSegment _0(9.0.0):C32:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+14-561, java.version=15-ea, timestamp=1584633450207, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-42-generic, java.runtime.version=15-ea+14-561}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [BD][qtp1449178457-609]: push new packet (delGen=1 numDeleteTerms=32 bytesUsed=1040), packetCount=1, bytesUsed=0.001 MB
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [BD][qtp1449178457-609]: finished packet delGen=2 now completedDelGen=0
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: publish sets newSegment delGen=2 seg=_0(9.0.0):C32:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+14-561, java.version=15-ea, timestamp=1584633450207, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-42-generic, java.runtime.version=15-ea+14-561}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-609]: now checkpoint "_0(9.0.0):C32:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+14-561, java.version=15-ea, timestamp=1584633450207, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-42-generic, java.runtime.version=15-ea+14-561}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-609]: 0 msec to checkpoint
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [BD][qtp1449178457-609]: packet matches no segments
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [BD][qtp1449178457-609]: finished packet delGen=1 now completedDelGen=2
[junit4] 2> 31343 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [BD][qtp1449178457-609]: done apply del packet (delGen=1 numDeleteTerms=32 bytesUsed=1040) to 0 segments; 0 new deletes/updates; took 0.000 sec; 0 packets remain
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [BD][qtp1449178457-609]: waitApply: no deletes to apply
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-609]: qtp1449178457-609 finishFullFlush success=true
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: startCommit(): start
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: startCommit index=_0(9.0.0):C32:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+14-561, java.version=15-ea, timestamp=1584633450207, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-42-generic, java.runtime.version=15-ea+14-561}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] changeCount=4
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: startCommit: wrote pending segments file "pending_segments_2"
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: done all syncs: [_0_Lucene84_0.pos, _0.si, _0.fdm, _0_Lucene84_0.doc, _0_Lucene84_0.tim, _0.nvd, _0_Lucene84_0.tip, _0.fdx, _0.fdt, _0.tvx, _0_Lucene80_0.dvm, _0.dii, _0.tvd, _0_Lucene80_0.dvd, _0_Lucene84_0.pay, _0.tvm, _0.dim, _0.nvm, _0.fnm]
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: commit: pendingCommit != null
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: commit: done writing segments file "segments_2"
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-609]: now checkpoint "_0(9.0.0):C32:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+14-561, java.version=15-ea, timestamp=1584633450207, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-42-generic, java.runtime.version=15-ea+14-561}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = true]
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-609]: deleteCommits: now decRef commit "segments_1"
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-609]: delete [segments_1]
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][qtp1449178457-609]: 0 msec to checkpoint
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: commit: took 10.3 msec
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: commit: done
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MP][qtp1449178457-609]: seg=_0(9.0.0):C32:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+14-561, java.version=15-ea, timestamp=1584633450207, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-42-generic, java.runtime.version=15-ea+14-561}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}] size=0.024 MB [floored]
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MP][qtp1449178457-609]: findMerges: 1 segments
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MP][qtp1449178457-609]: allowedSegmentCount=10 vs count=1 (eligible count=1)
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MS][qtp1449178457-609]: initDynamicDefaults spins=false maxThreadCount=1 maxMergeCount=6
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MS][qtp1449178457-609]: now merge
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MS][qtp1449178457-609]: index: _0(9.0.0):C32:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+14-561, java.version=15-ea, timestamp=1584633450207, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-42-generic, java.runtime.version=15-ea+14-561}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [MS][qtp1449178457-609]: no more merges pending; now return
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: flush at getReader
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-609]: startFullFlush
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: now apply all deletes for all segments buffered updates bytesUsed=0 reader pool bytesUsed=0
[junit4] 2> 31344 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [BD][qtp1449178457-609]: waitApply: no deletes to apply
[junit4] 2> 31346 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: incRefDeleter for NRT reader version=6 segments=_0(9.0.0):C32:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+14-561, java.version=15-ea, timestamp=1584633450207, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-42-generic, java.runtime.version=15-ea+14-561}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 31346 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: return reader version=6 reader=StandardDirectoryReader(segments_2:6:nrt _0(9.0.0):C32:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+14-561, java.version=15-ea, timestamp=1584633450207, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-42-generic, java.runtime.version=15-ea+14-561}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])
[junit4] 2> 31346 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][qtp1449178457-609]: qtp1449178457-609 finishFullFlush success=true
[junit4] 2> 31346 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][qtp1449178457-609]: getReader took 2 msec
[junit4] 2> 31347 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2dd92bcf[techproducts_shard1_replica_n1] main]
[junit4] 2> 31347 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 31356 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 c:techproducts s:shard1 r:core_node2) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@2dd92bcf[techproducts_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C32:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+14-561, java.version=15-ea, timestamp=1584633450207, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-42-generic, java.runtime.version=15-ea+14-561}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 31356 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 c:techproducts s:shard1 r:core_node2) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 31358 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 c:techproducts s:shard1 r:core_node2) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.SolrCore [techproducts_shard1_replica_n1] Registered new searcher Searcher@2dd92bcf[techproducts_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C32:[diagnostics={os.arch=amd64, source=flush, lucene.version=9.0.0, java.vm.version=15-ea+14-561, java.version=15-ea, timestamp=1584633450207, java.vendor=Oracle Corporation, os=Linux, os.version=5.3.0-42-generic, java.runtime.version=15-ea+14-561}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 31358 INFO (searcherExecutor-88-thread-1-processing-n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 c:techproducts s:shard1 r:core_node2) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][searcherExecutor-88-thread-1-processing-n:127.0.0.1:44613_solr x:techproducts_shard1_replica_n1 c:techproducts s:shard1 r:core_node2]: decRefDeleter for NRT reader version=2 segments=
[junit4] 2> 31360 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/techproducts/terms/shard1 to Terms{values={core_node2=1}, version=1}
[junit4] 2> 31360 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [techproducts_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=techproducts:4&waitSearcher=true&commit=true&softCommit=false&collection=techproducts&wt=javabin&version=2}{add=[TWINX2048-3200PRO (1661608604649652224), VS1GB400C3 (1661608604651749376), VDBDB1A16 (1661608604652797952), MA147LL/A (1661608604653846528), F8V7067-APL-KIT (1661608604656992256), IW-02 (1661608604658040832), 9885A004 (1661608604659089408), VA902B (1661608604660137984), EN7800GTX/2DHTV/256M (1661608604661186560), 100-435805 (1661608604662235136), ... (32 adds)], commit=} 0 56
[junit4] 2> 31373 INFO (TEST-JsonRequestApiTest.testFacetSortedByNestedMetric-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testFacetSortedByNestedMetric
[junit4] 2> 31410 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=cat,memSize=4665,tindexSize=46,time=0,phase1=0,nTerms=16,bigTerms=2,termInstances=21,uses=0}
[junit4] 2> 31413 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.S.Request [techproducts_shard1_replica_n1] webapp=/solr path=/select params={_stateVer_=techproducts:4&wt=javabin&version=2} hits=32 status=0 QTime=33
[junit4] 2> 31418 INFO (TEST-JsonRequestApiTest.testFacetSortedByNestedMetric-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testFacetSortedByNestedMetric
[junit4] 2> 31432 INFO (TEST-JsonRequestApiTest.testNestedFacetSimple-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNestedFacetSimple
[junit4] 2> 31438 INFO (qtp1449178457-603) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.S.Request [techproducts_shard1_replica_n1] webapp=/solr path=/select params={_stateVer_=techproducts:4&wt=javabin&version=2} hits=32 status=0 QTime=3
[junit4] 2> 31440 INFO (TEST-JsonRequestApiTest.testNestedFacetSimple-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNestedFacetSimple
[junit4] 2> 31462 INFO (TEST-JsonRequestApiTest.testStatFacetExpanded-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStatFacetExpanded
[junit4] 2> 31469 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.S.Request [techproducts_shard1_replica_n1] webapp=/solr path=/select params={_stateVer_=techproducts:4&wt=javabin&version=2} hits=13 status=0 QTime=4
[junit4] 2> 31473 INFO (TEST-JsonRequestApiTest.testStatFacetExpanded-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStatFacetExpanded
[junit4] 2> 31488 INFO (TEST-JsonRequestApiTest.testRangeFacetSimple-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRangeFacetSimple
[junit4] 2> 31496 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.S.Request [techproducts_shard1_replica_n1] webapp=/solr path=/select params={_stateVer_=techproducts:4&wt=javabin&version=2} hits=32 status=0 QTime=6
[junit4] 2> 31498 INFO (TEST-JsonRequestApiTest.testRangeFacetSimple-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRangeFacetSimple
[junit4] 2> 31509 INFO (TEST-JsonRequestApiTest.testFacetWidenedUsingQueryDomain-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testFacetWidenedUsingQueryDomain
[junit4] 2> 31526 INFO (qtp1449178457-603) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.S.Request [techproducts_shard1_replica_n1] webapp=/solr path=/select params={_stateVer_=techproducts:4&wt=javabin&version=2} hits=1 status=0 QTime=15
[junit4] 2> 31528 INFO (TEST-JsonRequestApiTest.testFacetWidenedUsingQueryDomain-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testFacetWidenedUsingQueryDomain
[junit4] 2> 31540 INFO (TEST-JsonRequestApiTest.testJsonQueryMacroExpansion-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testJsonQueryMacroExpansion
[junit4] 2> 31542 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.S.Request [techproducts_shard1_replica_n1] webapp=/solr path=/select params={_stateVer_=techproducts:4&FIELD=text&TERM=memory&wt=javabin&version=2} hits=5 status=0 QTime=1
[junit4] 2> 31544 INFO (TEST-JsonRequestApiTest.testJsonQueryMacroExpansion-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testJsonQueryMacroExpansion
[junit4] 2> 31555 INFO (TEST-JsonRequestApiTest.testJsonQueryUsingParamsBlock-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testJsonQueryUsingParamsBlock
[junit4] 2> 31558 INFO (qtp1449178457-605) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.S.Request [techproducts_shard1_replica_n1] webapp=/solr path=/select params={_stateVer_=techproducts:4&fl=name&fl=price&wt=javabin&version=2} hits=5 status=0 QTime=1
[junit4] 2> 31559 INFO (TEST-JsonRequestApiTest.testJsonQueryUsingParamsBlock-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testJsonQueryUsingParamsBlock
[junit4] 2> 31568 INFO (TEST-JsonRequestApiTest.testJsonQueryDslBoostEquivalents-seed#[54F18BFF063FEA0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testJsonQueryDslBoostEquivalents
[junit4] 2> 31574 INFO (qtp1449178457-603) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.S.Request [techproducts_shard1_replica_n1] webapp=/solr path=/select params={q={!boost+b%3Dlog(popularity)+v%3D'{!lucene+df%3Dname}iPod'}&_stateVer_=techproducts:4&wt=javabin&version=2} hits=3 status=0 QTime=5
[junit4] 2> 31578 INFO (qtp1449178457-609) [n:127.0.0.1:44613_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.c.S.Request [techproducts_shard1_replica_n1]
[...truncated too long message...]
e2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-19-thread-1]: commit: done
[junit4] 2> 8585 INFO (coreCloseExecutor-19-thread-1) [n:127.0.0.1:37771_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-19-thread-1]: rollback
[junit4] 2> 8585 INFO (coreCloseExecutor-19-thread-1) [n:127.0.0.1:37771_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-19-thread-1]: all running merges have aborted
[junit4] 2> 8585 INFO (coreCloseExecutor-19-thread-1) [n:127.0.0.1:37771_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-19-thread-1]: rollback: done finish merges
[junit4] 2> 8585 INFO (coreCloseExecutor-19-thread-1) [n:127.0.0.1:37771_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-19-thread-1]: abort
[junit4] 2> 8585 INFO (coreCloseExecutor-19-thread-1) [n:127.0.0.1:37771_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-19-thread-1]: done abort success=true
[junit4] 2> 8585 INFO (coreCloseExecutor-19-thread-1) [n:127.0.0.1:37771_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-19-thread-1]: rollback: infos=_0(9.0.0):C32:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=15-ea+14-561, os.version=5.3.0-42-generic, os=Linux, java.vendor=Oracle Corporation, timestamp=1584633797409, java.version=15-ea, java.vm.version=15-ea+14-561, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]
[junit4] 2> 8585 INFO (coreCloseExecutor-19-thread-1) [n:127.0.0.1:37771_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-19-thread-1]: now checkpoint "_0(9.0.0):C32:[diagnostics={source=flush, os.arch=amd64, java.runtime.version=15-ea+14-561, os.version=5.3.0-42-generic, os=Linux, java.vendor=Oracle Corporation, timestamp=1584633797409, java.version=15-ea, java.vm.version=15-ea+14-561, lucene.version=9.0.0}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]" [1 segments ; isCommit = false]
[junit4] 2> 8585 INFO (coreCloseExecutor-19-thread-1) [n:127.0.0.1:37771_solr c:techproducts s:shard1 r:core_node2 x:techproducts_shard1_replica_n1 ] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-19-thread-1]: 0 msec to checkpoint
[junit4] 2> 8592 INFO (jetty-closer-28-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 8592 INFO (jetty-closer-28-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3dac382c: rootName = solr_37771, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@7cf5afbb
[junit4] 2> 8598 INFO (jetty-closer-28-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 8598 INFO (jetty-closer-28-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f7dba4a: rootName = solr_37771, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5bf13899
[junit4] 2> 8600 INFO (jetty-closer-28-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 8600 INFO (jetty-closer-28-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5200064b: rootName = solr_37771, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5c8bfda5
[junit4] 2> 8600 INFO (jetty-closer-28-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 8601 INFO (closeThreadPool-32-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72074550973300740-127.0.0.1:37771_solr-n_0000000000) closing
[junit4] 2> 8601 INFO (OverseerAutoScalingTriggerThread-72074550973300740-127.0.0.1:37771_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 8601 INFO (OverseerStateUpdate-72074550973300740-127.0.0.1:37771_solr-n_0000000000) [n:127.0.0.1:37771_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:37771_solr
[junit4] 2> 8603 INFO (closeThreadPool-32-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72074550973300740-127.0.0.1:37771_solr-n_0000000000) closing
[junit4] 2> 8706 INFO (jetty-closer-28-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72074550973300740-127.0.0.1:37771_solr-n_0000000000) closing
[junit4] 2> 8708 INFO (jetty-closer-28-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5a29c364{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 8708 INFO (jetty-closer-28-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5166c934{/solr,null,UNAVAILABLE}
[junit4] 2> 8708 INFO (jetty-closer-28-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 8709 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 8912 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/collections/techproducts/terms/shard1
[junit4] 2> 2 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/collections/techproducts/state.json
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/overseer/queue
[junit4] 2> 4 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/live_nodes
[junit4] 2>
[junit4] 2> 8915 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:43233
[junit4] 2> 8915 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43233
[junit4] 2> 8915 INFO (SUITE-JsonRequestApiTest-seed#[54F18BFF063FEA0]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43233
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J4/temp/solr.client.ref_guide_examples.JsonRequestApiTest_54F18BFF063FEA0-001
[junit4] 2> Mar 19, 2020 4:03:18 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene84, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3ebb7a1), locale=es-PY, timezone=Europe/Gibraltar
[junit4] 2> NOTE: Linux 5.3.0-42-generic amd64/Oracle Corporation 15-ea (64-bit)/cpus=16,threads=2,free=347728184,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [JsonRequestApiTest]
[junit4] Completed [5/5 (5!)] on J4 in 9.24s, 22 tests, 1 failure <<< FAILURES!
[...truncated 17 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1516: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1043: There were test failures: 5 suites, 110 tests, 5 failures [seed: 54F18BFF063FEA0]
Total time: 11 seconds
[repro] Setting last failure code to 256
[repro] Failures w/original seeds:
[repro] 5/5 failed: org.apache.solr.client.ref_guide_examples.JsonRequestApiTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=78e670f19ec253fb5e9dc3980986feb501762995, workspace=/home/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #26056
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2