You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/07/13 14:15:08 UTC
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 35 -
Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/35/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting
Error Message:
Should have exactly 4 documents returned expected:<4> but was:<2>
Stack Trace:
java.lang.AssertionError: Should have exactly 4 documents returned expected:<4> but was:<2>
at __randomizedtesting.SeedInfo.seed([FF3F437EFE9172A4:E1074B76823AC824]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.cloud.DocValuesNotIndexedTest.checkSortOrder(DocValuesNotIndexedTest.java:259)
at org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting(DocValuesNotIndexedTest.java:244)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelCommitStream
Error Message:
expected:<5> but was:<3>
Stack Trace:
java.lang.AssertionError: expected:<5> but was:<3>
at __randomizedtesting.SeedInfo.seed([D0EB66DC18238B52:F00104DC8462661E]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.client.solrj.io.stream.StreamExpressionTest.testParallelCommitStream(StreamExpressionTest.java:4582)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 11244 lines...]
[junit4] Suite: org.apache.solr.cloud.DocValuesNotIndexedTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/init-core-data-001
[junit4] 2> 497922 WARN (SUITE-DocValuesNotIndexedTest-seed#[FF3F437EFE9172A4]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10
[junit4] 2> 497922 INFO (SUITE-DocValuesNotIndexedTest-seed#[FF3F437EFE9172A4]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 497923 INFO (SUITE-DocValuesNotIndexedTest-seed#[FF3F437EFE9172A4]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 497924 INFO (SUITE-DocValuesNotIndexedTest-seed#[FF3F437EFE9172A4]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001
[junit4] 2> 497924 INFO (SUITE-DocValuesNotIndexedTest-seed#[FF3F437EFE9172A4]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 497924 INFO (Thread-1242) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 497924 INFO (Thread-1242) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 497926 ERROR (Thread-1242) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 498024 INFO (SUITE-DocValuesNotIndexedTest-seed#[FF3F437EFE9172A4]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:34215
[junit4] 2> 498038 INFO (jetty-launcher-947-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 498039 INFO (jetty-launcher-947-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a349bee{/solr,null,AVAILABLE}
[junit4] 2> 498039 INFO (jetty-launcher-947-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@16c44c14{HTTP/1.1,[http/1.1]}{127.0.0.1:58808}
[junit4] 2> 498039 INFO (jetty-launcher-947-thread-1) [ ] o.e.j.s.Server Started @500106ms
[junit4] 2> 498039 INFO (jetty-launcher-947-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58808}
[junit4] 2> 498039 ERROR (jetty-launcher-947-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 498039 INFO (jetty-launcher-947-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0
[junit4] 2> 498039 INFO (jetty-launcher-947-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 498039 INFO (jetty-launcher-947-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 498039 INFO (jetty-launcher-947-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-13T13:18:53.346Z
[junit4] 2> 498038 INFO (jetty-launcher-947-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 498049 INFO (jetty-launcher-947-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d7c2c2a{/solr,null,AVAILABLE}
[junit4] 2> 498050 INFO (jetty-launcher-947-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7c390225{HTTP/1.1,[http/1.1]}{127.0.0.1:33899}
[junit4] 2> 498050 INFO (jetty-launcher-947-thread-2) [ ] o.e.j.s.Server Started @500117ms
[junit4] 2> 498050 INFO (jetty-launcher-947-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33899}
[junit4] 2> 498050 ERROR (jetty-launcher-947-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 498050 INFO (jetty-launcher-947-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.1.0
[junit4] 2> 498050 INFO (jetty-launcher-947-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 498050 INFO (jetty-launcher-947-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 498050 INFO (jetty-launcher-947-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-13T13:18:53.357Z
[junit4] 2> 498052 INFO (jetty-launcher-947-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 498058 INFO (jetty-launcher-947-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34215/solr
[junit4] 2> 498059 INFO (jetty-launcher-947-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 498064 INFO (jetty-launcher-947-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34215/solr
[junit4] 2> 498151 INFO (jetty-launcher-947-thread-1) [n:127.0.0.1:58808_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 498151 INFO (jetty-launcher-947-thread-1) [n:127.0.0.1:58808_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58808_solr
[junit4] 2> 498152 INFO (jetty-launcher-947-thread-1) [n:127.0.0.1:58808_solr ] o.a.s.c.Overseer Overseer (id=98300849896292357-127.0.0.1:58808_solr-n_0000000000) starting
[junit4] 2> 498164 INFO (jetty-launcher-947-thread-1) [n:127.0.0.1:58808_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58808_solr
[junit4] 2> 498165 INFO (zkCallback-958-thread-1-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 498285 INFO (jetty-launcher-947-thread-1) [n:127.0.0.1:58808_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58808.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 498287 INFO (jetty-launcher-947-thread-1) [n:127.0.0.1:58808_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58808.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 498287 INFO (jetty-launcher-947-thread-1) [n:127.0.0.1:58808_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58808.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 498288 INFO (jetty-launcher-947-thread-1) [n:127.0.0.1:58808_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node1/.
[junit4] 2> 498319 INFO (jetty-launcher-947-thread-2) [n:127.0.0.1:33899_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 498322 INFO (jetty-launcher-947-thread-2) [n:127.0.0.1:33899_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 498324 INFO (jetty-launcher-947-thread-2) [n:127.0.0.1:33899_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33899_solr
[junit4] 2> 498326 INFO (zkCallback-958-thread-1-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 498327 INFO (zkCallback-959-thread-1-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 498394 INFO (jetty-launcher-947-thread-2) [n:127.0.0.1:33899_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33899.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 498396 INFO (jetty-launcher-947-thread-2) [n:127.0.0.1:33899_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33899.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 498396 INFO (jetty-launcher-947-thread-2) [n:127.0.0.1:33899_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33899.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 498397 INFO (jetty-launcher-947-thread-2) [n:127.0.0.1:33899_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node2/.
[junit4] 2> 498425 INFO (SUITE-DocValuesNotIndexedTest-seed#[FF3F437EFE9172A4]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 498426 INFO (SUITE-DocValuesNotIndexedTest-seed#[FF3F437EFE9172A4]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34215/solr ready
[junit4] 2> 498432 INFO (qtp1167440954-5421) [n:127.0.0.1:58808_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 498434 INFO (OverseerThreadFactory-2593-thread-1-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.c.CreateCollectionCmd Create collection dv_coll
[junit4] 2> 498544 INFO (OverseerStateUpdate-98300849896292357-127.0.0.1:58808_solr-n_0000000000) [n:127.0.0.1:58808_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"dv_coll_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33899/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 498547 INFO (OverseerStateUpdate-98300849896292357-127.0.0.1:58808_solr-n_0000000000) [n:127.0.0.1:58808_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"dv_coll_shard2_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:58808/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 498548 INFO (OverseerStateUpdate-98300849896292357-127.0.0.1:58808_solr-n_0000000000) [n:127.0.0.1:58808_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"dv_coll_shard3_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33899/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 498550 INFO (OverseerStateUpdate-98300849896292357-127.0.0.1:58808_solr-n_0000000000) [n:127.0.0.1:58808_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard4",
[junit4] 2> "core":"dv_coll_shard4_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:58808/solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 498753 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 498754 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 498754 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node1&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 498757 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 498757 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 498758 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 498880 INFO (zkCallback-958-thread-1-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 498880 INFO (zkCallback-959-thread-1-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 498882 INFO (zkCallback-959-thread-2-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 498883 INFO (zkCallback-958-thread-2-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 499775 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 499775 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 499782 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.s.IndexSchema [dv_coll_shard4_replica_n1] Schema name=minimal
[junit4] 2> 499784 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 499784 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard4_replica_n1' using configuration from collection dv_coll, trusted=true
[junit4] 2> 499785 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58808.solr.core.dv_coll.shard4.replica_n1' (registry 'solr.core.dv_coll.shard4.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 499785 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 499785 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SolrCore [[dv_coll_shard4_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node1/dv_coll_shard4_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node1/./dv_coll_shard4_replica_n1/data/]
[junit4] 2> 499786 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.s.IndexSchema [dv_coll_shard2_replica_n1] Schema name=minimal
[junit4] 2> 499810 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 499816 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal
[junit4] 2> 499817 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 499818 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard1_replica_n1' using configuration from collection dv_coll, trusted=true
[junit4] 2> 499818 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 499818 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard2_replica_n1' using configuration from collection dv_coll, trusted=true
[junit4] 2> 499818 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33899.solr.core.dv_coll.shard1.replica_n1' (registry 'solr.core.dv_coll.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 499818 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58808.solr.core.dv_coll.shard2.replica_n1' (registry 'solr.core.dv_coll.shard2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 499818 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 499818 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 499818 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [[dv_coll_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node2/dv_coll_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node2/./dv_coll_shard1_replica_n1/data/]
[junit4] 2> 499818 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.SolrCore [[dv_coll_shard2_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node1/dv_coll_shard2_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node1/./dv_coll_shard2_replica_n1/data/]
[junit4] 2> 499830 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 499901 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n1] Schema name=minimal
[junit4] 2> 499903 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 499903 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard3_replica_n1' using configuration from collection dv_coll, trusted=true
[junit4] 2> 499903 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33899.solr.core.dv_coll.shard3.replica_n1' (registry 'solr.core.dv_coll.shard3.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 499903 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 499903 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SolrCore [[dv_coll_shard3_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node2/dv_coll_shard3_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node2/./dv_coll_shard3_replica_n1/data/]
[junit4] 2> 499907 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 499907 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 499908 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 499908 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 499909 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@43f2cf45[dv_coll_shard2_replica_n1] main]
[junit4] 2> 499912 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 499913 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 499913 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 499913 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 499914 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 499914 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1572813600426295296
[junit4] 2> 499921 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 499922 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 499923 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58808/solr/dv_coll_shard2_replica_n1/
[junit4] 2> 499923 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 499923 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:58808/solr/dv_coll_shard2_replica_n1/ has no replicas
[junit4] 2> 499923 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 499929 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:58808/solr/dv_coll_shard2_replica_n1/ shard2
[junit4] 2> 499930 INFO (searcherExecutor-2600-thread-1-processing-n:127.0.0.1:58808_solr x:dv_coll_shard2_replica_n1 s:shard2 c:dv_coll r:core_node2) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.SolrCore [dv_coll_shard2_replica_n1] Registered new searcher Searcher@43f2cf45[dv_coll_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 499936 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 499936 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 499937 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 499937 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 499940 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@18d174d6[dv_coll_shard4_replica_n1] main]
[junit4] 2> 499941 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 499941 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 499941 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 499942 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 499943 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 499943 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1572813600456704000
[junit4] 2> 499948 INFO (searcherExecutor-2598-thread-1-processing-n:127.0.0.1:58808_solr x:dv_coll_shard4_replica_n1 s:shard4 c:dv_coll r:core_node4) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SolrCore [dv_coll_shard4_replica_n1] Registered new searcher Searcher@18d174d6[dv_coll_shard4_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 499951 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 499951 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 499951 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58808/solr/dv_coll_shard4_replica_n1/
[junit4] 2> 499951 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 499951 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:58808/solr/dv_coll_shard4_replica_n1/ has no replicas
[junit4] 2> 499951 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 499955 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 499955 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 499956 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 499956 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 499958 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f974639[dv_coll_shard1_replica_n1] main]
[junit4] 2> 499958 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:58808/solr/dv_coll_shard4_replica_n1/ shard4
[junit4] 2> 499959 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 499960 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 499960 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 499961 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 499962 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 499962 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1572813600476626944
[junit4] 2> 499962 INFO (searcherExecutor-2599-thread-1-processing-n:127.0.0.1:33899_solr x:dv_coll_shard1_replica_n1 s:shard1 c:dv_coll r:core_node1) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher Searcher@f974639[dv_coll_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 499963 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 499963 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 499968 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 499968 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 499968 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33899/solr/dv_coll_shard1_replica_n1/
[junit4] 2> 499968 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 499968 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:33899/solr/dv_coll_shard1_replica_n1/ has no replicas
[junit4] 2> 499968 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 499972 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33899/solr/dv_coll_shard1_replica_n1/ shard1
[junit4] 2> 499978 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 499978 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 499979 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6183b705[dv_coll_shard3_replica_n1] main]
[junit4] 2> 499981 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 499981 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 499981 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 499982 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 499982 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 499983 INFO (searcherExecutor-2603-thread-1-processing-n:127.0.0.1:33899_solr x:dv_coll_shard3_replica_n1 s:shard3 c:dv_coll r:core_node3) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SolrCore [dv_coll_shard3_replica_n1] Registered new searcher Searcher@6183b705[dv_coll_shard3_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 499983 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1572813600498647040
[junit4] 2> 499990 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 499990 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 499990 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33899/solr/dv_coll_shard3_replica_n1/
[junit4] 2> 499990 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 499990 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:33899/solr/dv_coll_shard3_replica_n1/ has no replicas
[junit4] 2> 499990 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 499996 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33899/solr/dv_coll_shard3_replica_n1/ shard3
[junit4] 2> 500104 INFO (zkCallback-958-thread-2-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 500104 INFO (zkCallback-958-thread-1-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 500104 INFO (zkCallback-959-thread-2-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 500104 INFO (zkCallback-959-thread-1-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 500111 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 500113 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1356
[junit4] 2> 500127 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 500129 INFO (qtp1383366661-5431) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node1&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1375
[junit4] 2> 500131 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 500134 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1376
[junit4] 2> 500153 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 500156 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1403
[junit4] 2> 500160 INFO (qtp1167440954-5421) [n:127.0.0.1:58808_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 500259 INFO (zkCallback-959-thread-1-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 500259 INFO (zkCallback-959-thread-2-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 500259 INFO (zkCallback-958-thread-2-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 500260 INFO (zkCallback-958-thread-1-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 500437 INFO (OverseerCollectionConfigSetProcessor-98300849896292357-127.0.0.1:58808_solr-n_0000000000) [n:127.0.0.1:58808_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 501161 INFO (qtp1167440954-5421) [n:127.0.0.1:58808_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2} status=0 QTime=2730
[junit4] 2> 501168 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.SchemaManager managed schema loaded . version : 0
[junit4] 2> 501171 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n1] Schema name=minimal
[junit4] 2> 501192 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 501200 INFO (zkCallback-959-thread-1-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 501200 INFO (zkCallback-958-thread-2-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 501200 INFO (zkCallback-958-thread-1-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 501200 INFO (zkCallback-959-thread-2-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 501200 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.ZkController Persisted config data to node /configs/conf1/managed-schema
[junit4] 2> 501200 INFO (zkCallback-958-thread-2-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
[junit4] 2> 501200 INFO (zkCallback-958-thread-1-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
[junit4] 2> 501201 INFO (zkCallback-959-thread-2-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
[junit4] 2> 501203 INFO (zkCallback-958-thread-2-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.s.IndexSchema [dv_coll_shard2_replica_n1] Schema name=minimal
[junit4] 2> 501203 INFO (zkCallback-959-thread-2-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal
[junit4] 2> 501207 INFO (zkCallback-959-thread-2-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 501209 INFO (zkCallback-958-thread-2-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 501210 INFO (zkCallback-958-thread-1-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.s.IndexSchema [dv_coll_shard4_replica_n1] Schema name=minimal
[junit4] 2> 501214 INFO (zkCallback-958-thread-1-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 501216 INFO (Thread-1261) [n:127.0.0.1:58808_solr ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard4_replica_n1
[junit4] 2> 501218 INFO (Thread-1262) [n:127.0.0.1:33899_solr ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard1_replica_n1
[junit4] 2> 501218 INFO (Thread-1261) [n:127.0.0.1:58808_solr ] o.a.s.c.SolrCore core reload dv_coll_shard4_replica_n1
[junit4] 2> 501220 INFO (Thread-1262) [n:127.0.0.1:33899_solr ] o.a.s.c.SolrCore core reload dv_coll_shard1_replica_n1
[junit4] 2> 501222 INFO (zkCallback-959-thread-2-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 21 ms
[junit4] 2> 501222 INFO (zkCallback-958-thread-2-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 21 ms
[junit4] 2> 501222 INFO (zkCallback-958-thread-1-processing-n:127.0.0.1:58808_solr) [n:127.0.0.1:58808_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 21 ms
[junit4] 2> 501226 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 501227 INFO (Thread-1261) [n:127.0.0.1:58808_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 501255 INFO (Thread-1261) [n:127.0.0.1:58808_solr ] o.a.s.s.IndexSchema [dv_coll_shard4_replica_n1] Schema name=minimal
[junit4] 2> 501255 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n1] Schema name=minimal
[junit4] 2> 501259 INFO (Thread-1261) [n:127.0.0.1:58808_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 501260 INFO (Thread-1261) [n:127.0.0.1:58808_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard4_replica_n1' using configuration from collection dv_coll
[junit4] 2> 501273 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 501273 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard3_replica_n1' using configuration from collection dv_coll
[junit4] 2> 501280 INFO (Thread-1262) [n:127.0.0.1:33899_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 501295 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33899.solr.core.dv_coll.shard3.replica_n1' (registry 'solr.core.dv_coll.shard3.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 501295 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SolrCore [[dv_coll_shard3_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node2/dv_coll_shard3_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node2/./dv_coll_shard3_replica_n1/data/]
[junit4] 2> 501297 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58808.solr.core.dv_coll.shard4.replica_n1' (registry 'solr.core.dv_coll.shard4.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 501297 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SolrCore [[dv_coll_shard4_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node1/dv_coll_shard4_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node1/./dv_coll_shard4_replica_n1/data/]
[junit4] 2> 501300 INFO (Thread-1262) [n:127.0.0.1:33899_solr ] o.a.s.s.IndexSchema [dv_coll_shard1_replica_n1] Schema name=minimal
[junit4] 2> 501302 INFO (Thread-1262) [n:127.0.0.1:33899_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 501302 INFO (Thread-1262) [n:127.0.0.1:33899_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard1_replica_n1' using configuration from collection dv_coll
[junit4] 2> 501316 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33899.solr.core.dv_coll.shard1.replica_n1' (registry 'solr.core.dv_coll.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 501316 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [[dv_coll_shard1_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node2/dv_coll_shard1_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node2/./dv_coll_shard1_replica_n1/data/]
[junit4] 2> 501373 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 501373 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 501374 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c2da3f6[dv_coll_shard1_replica_n1] main]
[junit4] 2> 501382 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 501389 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 501389 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 501394 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 501394 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 501396 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 501396 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 501396 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 501397 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 501397 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5ac9316c[dv_coll_shard4_replica_n1] main]
[junit4] 2> 501398 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1572813601982382080
[junit4] 2> 501400 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 501402 INFO (searcherExecutor-2620-thread-1-processing-n:127.0.0.1:33899_solr x:dv_coll_shard1_replica_n1 s:shard1 c:dv_coll r:core_node1) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher Searcher@c2da3f6[dv_coll_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 501402 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@227c8a48[dv_coll_shard1_replica_n1] main]
[junit4] 2> 501402 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@14bd7479
[junit4] 2> 501403 INFO (searcherExecutor-2620-thread-1-processing-n:127.0.0.1:33899_solr x:dv_coll_shard1_replica_n1 s:shard1 c:dv_coll r:core_node1) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher Searcher@227c8a48[dv_coll_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 501404 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.dv_coll.shard1.replica_n1, tag=347960441
[junit4] 2> 501405 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.dv_coll.shard1.leader, tag=347960441
[junit4] 2> 501406 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore config update listener called for core dv_coll_shard3_replica_n1
[junit4] 2> 501406 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3a4f3a70[dv_coll_shard3_replica_n1] main]
[junit4] 2> 501406 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 501407 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 501408 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 501408 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore core reload dv_coll_shard3_replica_n1
[junit4] 2> 501409 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 501409 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 501410 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1572813601994964992
[junit4] 2> 501411 INFO (searcherExecutor-2618-thread-1-processing-n:127.0.0.1:58808_solr x:dv_coll_shard4_replica_n1 s:shard4 c:dv_coll r:core_node4) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SolrCore [dv_coll_shard4_replica_n1] Registered new searcher Searcher@5ac9316c[dv_coll_shard4_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 501412 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 501412 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@54027dd8[dv_coll_shard4_replica_n1] main]
[junit4] 2> 501412 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SolrCore [dv_coll_shard4_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@4f8f4ed0
[junit4] 2> 501413 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.dv_coll.shard4.replica_n1, tag=1334791888
[junit4] 2> 501433 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.dv_coll.shard4.leader, tag=1334791888
[junit4] 2> 501433 INFO (Thread-1261) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SolrCore config update listener called for core dv_coll_shard2_replica_n1
[junit4] 2> 501434 INFO (searcherExecutor-2618-thread-1-processing-n:127.0.0.1:58808_solr x:dv_coll_shard4_replica_n1 s:shard4 c:dv_coll r:core_node4) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.SolrCore [dv_coll_shard4_replica_n1] Registered new searcher Searcher@54027dd8[dv_coll_shard4_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 501436 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 501445 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 501445 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 501446 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 501446 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 501452 INFO (searcherExecutor-2619-thread-1-processing-n:127.0.0.1:33899_solr x:dv_coll_shard3_replica_n1 s:shard3 c:dv_coll r:core_node3) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SolrCore [dv_coll_shard3_replica_n1] Registered new searcher Searcher@3a4f3a70[dv_coll_shard3_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 501453 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1572813602040053760
[junit4] 2> 501455 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 501455 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a29165c[dv_coll_shard3_replica_n1] main]
[junit4] 2> 501456 INFO (searcherExecutor-2619-thread-1-processing-n:127.0.0.1:33899_solr x:dv_coll_shard3_replica_n1 s:shard3 c:dv_coll r:core_node3) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SolrCore [dv_coll_shard3_replica_n1] Registered new searcher Searcher@1a29165c[dv_coll_shard3_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 501463 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 3 replicas to apply schema update version 1 for collection dv_coll
[junit4] 2> 501463 INFO (zkCallback-959-thread-1-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from ZooKeeper
[junit4] 2> 501466 INFO (zkCallback-959-thread-1-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n1] Schema name=minimal
[junit4] 2> 501469 INFO (zkCallback-959-thread-1-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 501469 INFO (zkCallback-959-thread-1-processing-n:127.0.0.1:33899_solr) [n:127.0.0.1:33899_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 5 ms
[junit4] 2> 501469 INFO (qtp1167440954-5424) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.c.S.Request [dv_coll_shard2_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 501474 INFO (qtp1383366661-5435) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.S.Request [dv_coll_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 501476 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 501486 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.IndexSchema [dv_coll_shard3_replica_n1] Schema name=minimal
[junit4] 2> 501488 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 501488 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard3_replica_n1' using configuration from collection dv_coll
[junit4] 2> 501503 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33899.solr.core.dv_coll.shard3.replica_n1' (registry 'solr.core.dv_coll.shard3.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f3d962f
[junit4] 2> 501503 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [[dv_coll_shard3_replica_n1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node2/dv_coll_shard3_replica_n1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.DocValuesNotIndexedTest_FF3F437EFE9172A4-001/tempDir-001/node2/./dv_coll_shard3_replica_n1/data/]
[junit4] 2> 501506 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.c.S.Request [dv_coll_shard4_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 501527 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.ManagedIndexSchema Took 64.0ms for 3 replicas to apply schema update version 1 for collection dv_coll
[junit4] 2> 501527 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.S.Request [dv_coll_shard3_replica_n1] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=360
[junit4] 2> 501527 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.c.SolrCore [dv_coll_shard3_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@7d76a5d9
[junit4] 2> 501528 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.dv_coll.shard3.replica_n1, tag=2104927705
[junit4] 2> 501530 INFO (qtp1383366661-5433) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.dv_coll.shard3.leader, tag=2104927705
[junit4] 2> 501572 INFO (TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[FF3F437EFE9172A4]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGroupingSorting
[junit4] 2> 501578 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 501578 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 501581 INFO (qtp1167440954-5422) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58808/solr/dv_coll_shard4_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1572813602169028608)} 0 5
[junit4] 2> 501583 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@45f702a8[dv_coll_shard3_replica_n1] main]
[junit4] 2> 501584 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 501584 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 501584 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 501585 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 501585 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 501586 INFO (searcherExecutor-2631-thread-1-processing-n:127.0.0.1:33899_solr x:dv_coll_shard1_replica_n1 s:shard1 c:dv_coll r:core_node1) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.c.SolrCore [dv_coll_shard3_replica_n1] Registered new searcher Searcher@45f702a8[dv_coll_shard3_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 501598 INFO (qtp1383366661-5432) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58808/solr/dv_coll_shard4_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1572813602172174336)} 0 18
[junit4] 2> 501599 INFO (qtp1383366661-5430) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:58808/solr/dv_coll_shard4_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1572813602170077184)} 0 22
[junit4] 2> 501599 INFO (qtp1167440954-5420) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1572813602166931456)} 0 26
[junit4] 2> 501601 INFO (qtp1383366661-5434) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572813602195243008,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 501602 INFO (qtp1383366661-5434) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 501602 INFO (qtp1383366661-5434) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 501606 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 501606 INFO (Thread-1262) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@70bfb058[dv_coll_shard3_replica_n1] main]
[junit4] 2> 501607 INFO (qtp1383366661-5435) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572813602196291584,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 501607 INFO (qtp1383366661-5435) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 501607 INFO (qtp1383366661-5435) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@787c8cd8[dv_coll_shard3_replica_n1] main]
[junit4] 2> 501608 INFO (qtp1383366661-5435) [n:127.0.0.1:33899_solr c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 501608 INFO (qtp1383366661-5434) [n:127.0.0.1:33899_solr c:dv_coll s:shard1 r:core_node1 x:dv_coll_shard1_replica_n1] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:58808/solr/dv_coll_shard4_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 7
[junit4] 2> 501609 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572813602203631616,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 501609 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 501610 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@217798c7[dv_coll_shard2_replica_n1] main]
[junit4] 2> 501610 INFO (qtp1167440954-5423) [n:127.0.0.1:58808_solr c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 501610 INFO (qtp1167440954-5421) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572813602204680192,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 501610 INFO (qtp1167440954-5421) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 501610 INFO (qtp1167440954-5421) [n:127.0.0.1:58808_solr c:dv_coll s:shard4 r:core_node4 x:dv_coll_shard4_replica_n1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 501611 INFO (searcherExecutor-2600-thread-1-processing-n:127.0.0.1:58808
[...truncated too long message...]
cj-lint] ParallelStream parallelStream = new ParallelStream(zk, collection, tupleStream, numWorkers, comp);
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'parallelStream' is never closed
[ecj-lint] ----------
[ecj-lint] 8. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/handler/sql/SolrTable.java (at line 743)
[ecj-lint] ParallelStream parallelStream = new ParallelStream(zkHost, collection, tupleStream, numWorkers, comp);
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'parallelStream' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 9. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/highlight/DefaultSolrHighlighter.java (at line 578)
[ecj-lint] tvWindowStream = new OffsetWindowTokenFilter(tvStream);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'tvWindowStream' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 10. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/request/SimpleFacets.java (at line 912)
[ecj-lint] fastForRandomSet = new HashDocSet(sset.getDocs(), 0, sset.size());
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'fastForRandomSet' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 11. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/response/SmileResponseWriter.java (at line 33)
[ecj-lint] new SmileWriter(out, request, response).writeResponse();
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 12. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/schema/OpenExchangeRatesOrgProvider.java (at line 146)
[ecj-lint] ratesJsonStream = resourceLoader.openResource(ratesFileLocation);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'ratesJsonStream' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 13. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/search/CollapsingQParserPlugin.java (at line 1313)
[ecj-lint] UninvertingReader uninvertingReader = new UninvertingReader(new ReaderWrapper(searcher.getSlowAtomicReader(), collapseField), mapping);
[ecj-lint] ^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'uninvertingReader' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 14. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 145)
[ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len);
[ecj-lint] ^
[ecj-lint] Resource leak: 'h' is never closed
[ecj-lint] ----------
[ecj-lint] 15. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 154)
[ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len);
[ecj-lint] ^
[ecj-lint] Resource leak: 'h' is never closed
[ecj-lint] ----------
[ecj-lint] 16. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 163)
[ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len);
[ecj-lint] ^
[ecj-lint] Resource leak: 'h' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 17. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/search/function/OrdFieldSource.java (at line 78)
[ecj-lint] SolrIndexSearcher is = (SolrIndexSearcher) o;
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 18. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/search/function/ReverseOrdFieldSource.java (at line 78)
[ecj-lint] SolrIndexSearcher is = (SolrIndexSearcher) o;
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 19. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/servlet/SolrDispatchFilter.java (at line 287)
[ecj-lint] throw new SolrException(ErrorCode.SERVER_ERROR, "Error occurred while loading solr.xml from zookeeper", e);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'loader' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 20. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/update/CdcrTransactionLog.java (at line 98)
[ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos);
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 21. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/update/HdfsTransactionLog.java (at line 191)
[ecj-lint] LogCodec codec = new LogCodec(resolver);
[ecj-lint] ^^^^^
[ecj-lint] Resource leak: 'codec' is never closed
[ecj-lint] ----------
[ecj-lint] 22. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/update/HdfsTransactionLog.java (at line 261)
[ecj-lint] LogCodec codec = new LogCodec(resolver);
[ecj-lint] ^^^^^
[ecj-lint] Resource leak: 'codec' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 23. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 228)
[ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos);
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] 24. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 228)
[ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos);
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] 25. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 260)
[ecj-lint] LogCodec codec = new LogCodec(resolver);
[ecj-lint] ^^^^^
[ecj-lint] Resource leak: 'codec' is never closed
[ecj-lint] ----------
[ecj-lint] 26. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 275)
[ecj-lint] LogCodec codec = new LogCodec(resolver);
[ecj-lint] ^^^^^
[ecj-lint] Resource leak: 'codec' is never closed
[ecj-lint] ----------
[ecj-lint] 27. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 529)
[ecj-lint] LogCodec codec = new LogCodec(resolver);
[ecj-lint] ^^^^^
[ecj-lint] Resource leak: 'codec' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 28. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/update/processor/UpdateRequestProcessor.java (at line 85)
[ecj-lint] UpdateRequestProcessor p = this;
[ecj-lint] ^
[ecj-lint] Resource leak: 'p' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 29. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/util/SimplePostTool.java (at line 655)
[ecj-lint] BAOS bos = new BAOS();
[ecj-lint] ^^^
[ecj-lint] Resource leak: 'bos' is never closed
[ecj-lint] ----------
[ecj-lint] 30. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/util/SimplePostTool.java (at line 655)
[ecj-lint] BAOS bos = new BAOS();
[ecj-lint] ^^^
[ecj-lint] Resource leak: 'bos' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 31. WARNING in /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/java/org/apache/solr/util/SolrLogLayout.java (at line 149)
[ecj-lint] SolrQueryRequest req = requestInfo == null ? null : requestInfo.getReq();
[ecj-lint] ^^^
[ecj-lint] Resource leak: 'req' is never closed
[ecj-lint] ----------
[ecj-lint] 31 problems (1 error, 30 warnings)
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/build.xml:810: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/build.xml:101: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build.xml:689: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/common-build.xml:2004: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/lucene/common-build.xml:2043: Compile failed; see the compiler error output for details.
Total time: 76 minutes 33 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-7.x-Solaris (64bit/jdk1.8.0) - Build # 36 -
Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/36/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.request.SimpleFacetsTest.testDvMethodNegativeFloatRangeFacet
Error Message:
Unexpected schema configuration expected: com.fasterxml.jackson.core.JsonParser$NumberType<FLOAT> but was: org.apache.solr.schema.NumberType<FLOAT>
Stack Trace:
java.lang.AssertionError: Unexpected schema configuration expected: com.fasterxml.jackson.core.JsonParser$NumberType<FLOAT> but was: org.apache.solr.schema.NumberType<FLOAT>
at __randomizedtesting.SeedInfo.seed([4883D07AC2D07A83:64208F54BFE462E5]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.apache.solr.request.SimpleFacetsTest.testDvMethodNegativeFloatRangeFacet(SimpleFacetsTest.java:190)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 11998 lines...]
[junit4] Suite: org.apache.solr.request.SimpleFacetsTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.request.SimpleFacetsTest_4883D07AC2D07A83-001/init-core-data-001
[junit4] 2> 1566749 WARN (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=17 numCloses=17
[junit4] 2> 1566749 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1566750 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1566751 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1566751 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1566769 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1566783 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1566843 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1566870 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@28344f46
[junit4] 2> 1566872 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@28344f46
[junit4] 2> 1566873 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@28344f46
[junit4] 2> 1566874 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib, /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1566895 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
[junit4] 2> 1566907 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1566967 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1566972 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1566973 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@28344f46
[junit4] 2> 1566973 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1566973 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1], dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.request.SimpleFacetsTest_4883D07AC2D07A83-001/init-core-data-001/]
[junit4] 2> 1566974 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=30.259765625, floorSegmentMB=0.63671875, forceMergeDeletesPctAllowed=26.037661312216734, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1567022 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1567023 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1567023 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1567023 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1567024 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1567024 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b20ffda[collection1] main]
[junit4] 2> 1567024 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1567027 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1567049 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1567049 INFO (coreLoadExecutor-7683-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1572843528241807360
[junit4] 2> 1567052 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1567052 INFO (searcherExecutor-7684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1567052 INFO (searcherExecutor-7684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1567052 INFO (searcherExecutor-7684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1567052 INFO (searcherExecutor-7684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1567053 INFO (searcherExecutor-7684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1567053 INFO (searcherExecutor-7684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1567053 INFO (searcherExecutor-7684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1567053 INFO (searcherExecutor-7684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1567054 INFO (searcherExecutor-7684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1567054 INFO (searcherExecutor-7684-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2b20ffda[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1567054 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=2
[junit4] 2> 1567055 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t} hits=0 status=0 QTime=0
[junit4] 2> 1567055 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567055 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1567055 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567055 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1567055 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567055 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1567055 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567056 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1567056 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567056 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1567056 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=fc&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567056 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1567056 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567056 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1567056 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567056 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1567057 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=enum&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567057 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=empty_t,memSize=4320,tindexSize=32,time=0,phase1=0,nTerms=0,bigTerms=0,termInstances=0,uses=0}
[junit4] 2> 1567057 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1567060 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif&facet.missing=true} hits=0 status=0 QTime=3
[junit4] 2> 1567060 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1567060 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567061 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1567061 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_t&facet.method=uif&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567061 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1567061 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i} hits=0 status=0 QTime=0
[junit4] 2> 1567061 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567061 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1567061 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=fc&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567061 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1567061 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=enum&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567062 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1567062 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_i&facet.method=uif&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567062 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1567062 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f} hits=0 status=0 QTime=0
[junit4] 2> 1567062 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567062 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1567062 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=fc&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567062 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1567063 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=enum&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567063 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1567063 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_f&facet.method=uif&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567063 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1567063 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s} hits=0 status=0 QTime=0
[junit4] 2> 1567063 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567063 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1567063 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567063 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1567063 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567064 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1567064 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567064 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1567064 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567064 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1567064 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=fc&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567064 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1567064 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567064 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1567064 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567064 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1567065 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=enum&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567065 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.f.UnInvertedField UnInverted multi-valued field {field=empty_s,memSize=4320,tindexSize=32,time=0,phase1=0,nTerms=0,bigTerms=0,termInstances=0,uses=0}
[junit4] 2> 1567065 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1567065 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567065 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif&facet.prefix=myprefix} hits=0 status=0 QTime=0
[junit4] 2> 1567065 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif&facet.prefix=myprefix&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567065 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif&facet.prefix=} hits=0 status=0 QTime=0
[junit4] 2> 1567065 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_s&facet.method=uif&facet.prefix=&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567065 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 1567066 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d} hits=0 status=0 QTime=0
[junit4] 2> 1567066 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567066 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=fc} hits=0 status=0 QTime=0
[junit4] 2> 1567066 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=fc&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567066 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=enum} hits=0 status=0 QTime=0
[junit4] 2> 1567066 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=enum&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567066 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=uif} hits=0 status=0 QTime=0
[junit4] 2> 1567066 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&facet.mincount=1&rows=0&facet=true&facet.field={!key%3Dmyalias}empty_d&facet.method=uif&facet.missing=true} hits=0 status=0 QTime=0
[junit4] 2> 1567067 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528260681728,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567067 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1567067 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567067 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1567068 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528261730304,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567069 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1567069 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567069 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1567069 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528262778880,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567069 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1567069 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567069 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1567069 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528262778881,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567069 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1567069 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567069 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1567070 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[42 (1572843528263827456)]} 0 0
[junit4] 2> 1567072 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType dateRange{class=org.apache.solr.schema.DateRangeField,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args={class=solr.DateRangeField}} strat: NumberRangePrefixTreeStrategy(SPG:(DateRangePrefixTree),prefixGridScanLevel:7) maxLevels: 11
[junit4] 2> 1567074 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType dateRange{class=org.apache.solr.schema.DateRangeField,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args={class=solr.DateRangeField}} strat: NumberRangePrefixTreeStrategy(SPG:(DateRangePrefixTree),prefixGridScanLevel:7) maxLevels: 11
[junit4] 2> 1567074 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[208 (1572843528263827457)]} 0 3
[junit4] 2> 1567075 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[501 (1572843528269070336)]} 0 0
[junit4] 2> 1567075 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[45 (1572843528269070337)]} 0 0
[junit4] 2> 1567075 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[406 (1572843528269070338)]} 0 0
[junit4] 2> 1567075 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4013 (1572843528269070339)]} 0 0
[junit4] 2> 1567075 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528269070340,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567075 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528269070340
[junit4] 2> 1567080 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@502c3ca8[collection1] main]
[junit4] 2> 1567080 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567081 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@502c3ca8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6)))}
[junit4] 2> 1567081 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 1567082 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[702 (1572843528275361792)]} 0 0
[junit4] 2> 1567082 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528276410368,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567082 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528276410368
[junit4] 2> 1567084 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6679b484[collection1] main]
[junit4] 2> 1567084 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567085 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6679b484[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6) Uninverting(_1(7.1.0):c1)))}
[junit4] 2> 1567085 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1567086 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[45 (1572843528280604672)]} 0 0
[junit4] 2> 1567087 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2001 (1572843528280604673)]} 0 0
[junit4] 2> 1567087 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[44 (1572843528281653248)]} 0 0
[junit4] 2> 1567087 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528281653249,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567087 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528281653249
[junit4] 2> 1567090 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4f0ebee7[collection1] main]
[junit4] 2> 1567090 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567091 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4f0ebee7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/1:delGen=1) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3)))}
[junit4] 2> 1567091 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1567092 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8012 (1572843528285847552)]} 0 0
[junit4] 2> 1567092 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528286896128,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567092 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528286896128
[junit4] 2> 1567094 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@350cc802[collection1] main]
[junit4] 2> 1567095 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567095 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@350cc802[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/1:delGen=1) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1)))}
[junit4] 2> 1567096 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1567097 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[207 (1572843528291090432)]} 0 1
[junit4] 2> 1567097 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[709 (1572843528292139008)]} 0 0
[junit4] 2> 1567097 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[505 (1572843528292139009)]} 0 0
[junit4] 2> 1567098 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[46 (1572843528292139010)]} 0 0
[junit4] 2> 1567098 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[706 (1572843528293187584)]} 0 0
[junit4] 2> 1567098 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[406 (1572843528293187585)]} 0 0
[junit4] 2> 1567098 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7010 (1572843528293187586)]} 0 0
[junit4] 2> 1567099 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[204 (1572843528293187587)]} 0 0
[junit4] 2> 1567099 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528294236160,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567099 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528294236160
[junit4] 2> 1567102 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@25d8ec17[collection1] main]
[junit4] 2> 1567103 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567104 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@25d8ec17[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8)))}
[junit4] 2> 1567104 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1567105 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[605 (1572843528299479040)]} 0 0
[junit4] 2> 1567105 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528300527616,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567105 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528300527616
[junit4] 2> 1567107 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b38f49d[collection1] main]
[junit4] 2> 1567108 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567109 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7b38f49d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8) Uninverting(_5(7.1.0):c1)))}
[junit4] 2> 1567109 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1567110 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[701 (1572843528304721920)]} 0 0
[junit4] 2> 1567110 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528305770496,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567110 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528305770496
[junit4] 2> 1567112 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4c0d0f8d[collection1] main]
[junit4] 2> 1567112 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567113 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4c0d0f8d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1)))}
[junit4] 2> 1567113 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1567114 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[46 (1572843528308916224)]} 0 0
[junit4] 2> 1567114 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528309964800,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567114 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528309964800
[junit4] 2> 1567117 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@26835a54[collection1] main]
[junit4] 2> 1567117 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567118 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@26835a54[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/1:delGen=1) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_7(7.1.0):c1)))}
[junit4] 2> 1567118 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1567119 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[603 (1572843528315207680)]} 0 0
[junit4] 2> 1567119 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[504 (1572843528315207681)]} 0 0
[junit4] 2> 1567120 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[606 (1572843528316256256)]} 0 0
[junit4] 2> 1567120 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528316256257,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567120 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528316256257
[junit4] 2> 1567122 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@766e273[collection1] main]
[junit4] 2> 1567122 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567123 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@766e273[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/1:delGen=1) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_7(7.1.0):c1) Uninverting(_8(7.1.0):c3)))}
[junit4] 2> 1567124 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1567125 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[209 (1572843528320450560)]} 0 1
[junit4] 2> 1567126 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[602 (1572843528322547712)]} 0 0
[junit4] 2> 1567126 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6010 (1572843528322547713)]} 0 0
[junit4] 2> 1567126 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[705 (1572843528322547714)]} 0 0
[junit4] 2> 1567126 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[46 (1572843528322547715)]} 0 0
[junit4] 2> 1567127 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5012 (1572843528323596288)]} 0 0
[junit4] 2> 1567127 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528323596289,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567127 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528323596289
[junit4] 2> 1567130 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b02cc91[collection1] main]
[junit4] 2> 1567130 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567131 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7b02cc91[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/1:delGen=1) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6)))}
[junit4] 2> 1567132 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1567132 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[507 (1572843528328839168)]} 0 0
[junit4] 2> 1567133 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528328839169,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567133 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528328839169
[junit4] 2> 1567135 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d9b2c6[collection1] main]
[junit4] 2> 1567135 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567136 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d9b2c6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/1:delGen=1) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6) Uninverting(_a(7.1.0):c1)))}
[junit4] 2> 1567136 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1567137 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[209 (1572843528333033472)]} 0 1
[junit4] 2> 1567138 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[708 (1572843528335130624)]} 0 0
[junit4] 2> 1567138 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[406 (1572843528335130625)]} 0 0
[junit4] 2> 1567138 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7012 (1572843528335130626)]} 0 0
[junit4] 2> 1567138 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8011 (1572843528335130627)]} 0 0
[junit4] 2> 1567139 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2012 (1572843528335130628)]} 0 0
[junit4] 2> 1567139 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[409 (1572843528336179200)]} 0 0
[junit4] 2> 1567139 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4011 (1572843528336179201)]} 0 0
[junit4] 2> 1567139 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[509 (1572843528336179202)]} 0 0
[junit4] 2> 1567139 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5010 (1572843528336179203)]} 0 0
[junit4] 2> 1567140 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2011 (1572843528336179204)]} 0 0
[junit4] 2> 1567140 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4010 (1572843528337227776)]} 0 0
[junit4] 2> 1567140 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528337227777,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567140 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528337227777
[junit4] 2> 1567144 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@469330d[collection1] main]
[junit4] 2> 1567145 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567146 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@469330d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/2:delGen=2) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6/1:delGen=1) Uninverting(_a(7.1.0):c1) Uninverting(_b(7.1.0):c12)))}
[junit4] 2> 1567146 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 1567147 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[409 (1572843528343519232)]} 0 0
[junit4] 2> 1567147 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2004 (1572843528344567808)]} 0 0
[junit4] 2> 1567147 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[801 (1572843528344567809)]} 0 0
[junit4] 2> 1567147 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[102 (1572843528344567810)]} 0 0
[junit4] 2> 1567147 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528344567811,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567148 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528344567811
[junit4] 2> 1567150 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f4cf83a[collection1] main]
[junit4] 2> 1567150 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567151 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f4cf83a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/2:delGen=2) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6/1:delGen=1) Uninverting(_a(7.1.0):c1) Uninverting(_b(7.1.0):c12/1:delGen=1) Uninverting(_c(7.1.0):c4)))}
[junit4] 2> 1567151 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1567152 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[802 (1572843528349810688)]} 0 0
[junit4] 2> 1567153 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[203 (1572843528349810689)]} 0 0
[junit4] 2> 1567153 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2000 (1572843528350859264)]} 0 0
[junit4] 2> 1567153 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4011 (1572843528350859265)]} 0 0
[junit4] 2> 1567153 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[405 (1572843528350859266)]} 0 0
[junit4] 2> 1567154 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528351907840,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567154 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528351907840
[junit4] 2> 1567156 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4c7a82d7[collection1] main]
[junit4] 2> 1567157 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567157 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4c7a82d7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/2:delGen=2) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6/1:delGen=1) Uninverting(_a(7.1.0):c1) Uninverting(_b(7.1.0):c12/2:delGen=2) Uninverting(_c(7.1.0):c4) Uninverting(_d(7.1.0):c5)))}
[junit4] 2> 1567158 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1567159 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[207 (1572843528356102144)]} 0 1
[junit4] 2> 1567159 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6010 (1572843528357150720)]} 0 0
[junit4] 2> 1567160 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[202 (1572843528357150721)]} 0 0
[junit4] 2> 1567160 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[508 (1572843528358199296)]} 0 0
[junit4] 2> 1567160 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2010 (1572843528358199297)]} 0 0
[junit4] 2> 1567160 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[503 (1572843528358199298)]} 0 0
[junit4] 2> 1567161 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[503 (1572843528359247872)]} 0 0
[junit4] 2> 1567161 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528359247873,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567161 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528359247873
[junit4] 2> 1567164 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@557b58bd[collection1] main]
[junit4] 2> 1567164 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567165 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@557b58bd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/3:delGen=3) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6/2:delGen=2) Uninverting(_a(7.1.0):c1) Uninverting(_b(7.1.0):c12/2:delGen=2) Uninverting(_c(7.1.0):c4) Uninverting(_d(7.1.0):c5) Uninverting(_e(7.1.0):c7/1:delGen=1)))}
[junit4] 2> 1567165 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1567166 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2014 (1572843528364490752)]} 0 0
[junit4] 2> 1567167 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7011 (1572843528364490753)]} 0 0
[junit4] 2> 1567167 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6012 (1572843528365539328)]} 0 0
[junit4] 2> 1567167 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[809 (1572843528365539329)]} 0 0
[junit4] 2> 1567167 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[205 (1572843528365539330)]} 0 0
[junit4] 2> 1567167 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5011 (1572843528365539331)]} 0 0
[junit4] 2> 1567168 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528366587904,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567168 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528366587904
[junit4] 2> 1567170 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5169be0f[collection1] main]
[junit4] 2> 1567171 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567172 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5169be0f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_1(7.1.0):c1) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/3:delGen=3) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6/2:delGen=2) Uninverting(_a(7.1.0):c1) Uninverting(_b(7.1.0):c12/2:delGen=2) Uninverting(_c(7.1.0):c4) Uninverting(_d(7.1.0):c5) Uninverting(_e(7.1.0):c7/1:delGen=1) Uninverting(_f(7.1.0):c6)))}
[junit4] 2> 1567172 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1567172 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[702 (1572843528370782208)]} 0 0
[junit4] 2> 1567173 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528371830784,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567173 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528371830784
[junit4] 2> 1567175 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e3e2dcb[collection1] main]
[junit4] 2> 1567175 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567176 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e3e2dcb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/3:delGen=3) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6/2:delGen=2) Uninverting(_a(7.1.0):c1) Uninverting(_b(7.1.0):c12/2:delGen=2) Uninverting(_c(7.1.0):c4) Uninverting(_d(7.1.0):c5) Uninverting(_e(7.1.0):c7/1:delGen=1) Uninverting(_f(7.1.0):c6) Uninverting(_g(7.1.0):c1)))}
[junit4] 2> 1567176 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1567189 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4012 (1572843528388608000)]} 0 0
[junit4] 2> 1567190 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[601 (1572843528389656576)]} 0 0
[junit4] 2> 1567190 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528389656577,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567190 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528389656577
[junit4] 2> 1567192 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@51af12b2[collection1] main]
[junit4] 2> 1567193 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567193 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@51af12b2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/3:delGen=3) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6/2:delGen=2) Uninverting(_a(7.1.0):c1) Uninverting(_b(7.1.0):c12/2:delGen=2) Uninverting(_c(7.1.0):c4) Uninverting(_d(7.1.0):c5) Uninverting(_e(7.1.0):c7/1:delGen=1) Uninverting(_f(7.1.0):c6) Uninverting(_g(7.1.0):c1) Uninverting(_h(7.1.0):c2)))}
[junit4] 2> 1567194 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1567194 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[608 (1572843528393850880)]} 0 0
[junit4] 2> 1567195 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528394899456,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567195 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528394899456
[junit4] 2> 1567197 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@29d4d8a0[collection1] main]
[junit4] 2> 1567198 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567198 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@29d4d8a0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/3:delGen=3) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6/2:delGen=2) Uninverting(_a(7.1.0):c1) Uninverting(_b(7.1.0):c12/2:delGen=2) Uninverting(_c(7.1.0):c4) Uninverting(_d(7.1.0):c5) Uninverting(_e(7.1.0):c7/1:delGen=1) Uninverting(_f(7.1.0):c6) Uninverting(_g(7.1.0):c1) Uninverting(_h(7.1.0):c2) Uninverting(_i(7.1.0):c1)))}
[junit4] 2> 1567199 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1567199 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[403 (1572843528399093760)]} 0 0
[junit4] 2> 1567200 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528400142336,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567200 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528400142336
[junit4] 2> 1567202 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@59da37ee[collection1] main]
[junit4] 2> 1567203 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567203 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@59da37ee[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_2(7.1.0):c3) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/3:delGen=3) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6/2:delGen=2) Uninverting(_a(7.1.0):c1) Uninverting(_b(7.1.0):c12/2:delGen=2) Uninverting(_c(7.1.0):c4) Uninverting(_d(7.1.0):c5) Uninverting(_e(7.1.0):c7/1:delGen=1) Uninverting(_f(7.1.0):c6) Uninverting(_g(7.1.0):c1) Uninverting(_h(7.1.0):c2) Uninverting(_i(7.1.0):c1) Uninverting(_j(7.1.0):c1)))}
[junit4] 2> 1567204 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1567205 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[45 (1572843528404336640)]} 0 0
[junit4] 2> 1567205 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2002 (1572843528405385216)]} 0 0
[junit4] 2> 1567205 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[807 (1572843528405385217)]} 0 0
[junit4] 2> 1567205 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528405385218,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567205 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528405385218
[junit4] 2> 1567208 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7b2627f6[collection1] main]
[junit4] 2> 1567209 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567210 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7b2627f6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_2(7.1.0):c3/1:delGen=1) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/3:delGen=3) Uninverting(_5(7.1.0):c1) Uninverting(_6(7.1.0):c1) Uninverting(_8(7.1.0):c3) Uninverting(_9(7.1.0):c6/2:delGen=2) Uninverting(_a(7.1.0):c1) Uninverting(_b(7.1.0):c12/2:delGen=2) Uninverting(_c(7.1.0):c4) Uninverting(_d(7.1.0):c5) Uninverting(_e(7.1.0):c7/1:delGen=1) Uninverting(_f(7.1.0):c6) Uninverting(_g(7.1.0):c1) Uninverting(_h(7.1.0):c2) Uninverting(_i(7.1.0):c1) Uninverting(_j(7.1.0):c1) Uninverting(_k(7.1.0):c3)))}
[junit4] 2> 1567210 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 1567211 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[707 (1572843528411676672)]} 0 0
[junit4] 2> 1567211 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[404 (1572843528411676673)]} 0 0
[junit4] 2> 1567212 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[43 (1572843528412725248)]} 0 0
[junit4] 2> 1567212 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[804 (1572843528412725249)]} 0 0
[junit4] 2> 1567212 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6011 (1572843528412725250)]} 0 0
[junit4] 2> 1567213 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2011 (1572843528412725251)]} 0 0
[junit4] 2> 1567213 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1572843528413773824,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1567213 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@fc7d1f4 commitCommandVersion:1572843528413773824
[junit4] 2> 1567216 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f53d4a2[collection1] main]
[junit4] 2> 1567217 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1567217 INFO (searcherExecutor-7684-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f53d4a2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c6/2:delGen=2) Uninverting(_2(7.1.0):c3/1:delGen=1) Uninverting(_3(7.1.0):c1) Uninverting(_4(7.1.0):c8/3:delGen=3) Uninverting(_5(7.1.0):c1) U
[...truncated too long message...]
ath=null params={facet.query=bar_s:yak&q=*:*&facet.field=bar_s&qt=/search-facet-invariants&indent=true&rows=0&facet=true&wt=xml} hits=89 status=0 QTime=0
[junit4] 2> 1568472 INFO (TEST-SimpleFacetsTest.testInvariants-seed#[4883D07AC2D07A83]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&bogus=bar_s:yak&facet.field=bar_s&qt=/search-facet-invariants&indent=true&rows=0&facet=true&wt=xml} hits=89 status=0 QTime=0
[junit4] 2> 1568472 INFO (TEST-SimpleFacetsTest.testInvariants-seed#[4883D07AC2D07A83]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={facet.query=bar_s:yak&q=*:*&bogus=bar_s&qt=/search-facet-invariants&indent=true&rows=0&facet=true&wt=xml} hits=89 status=0 QTime=0
[junit4] 2> 1568473 INFO (TEST-SimpleFacetsTest.testInvariants-seed#[4883D07AC2D07A83]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&bogus=bar_s&bogus=bar_s:yak&qt=/search-facet-invariants&indent=true&rows=0&facet=true&wt=xml} hits=89 status=0 QTime=0
[junit4] 2> 1568473 INFO (TEST-SimpleFacetsTest.testInvariants-seed#[4883D07AC2D07A83]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testInvariants
[junit4] 2> 1568473 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1568473 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1940814519
[junit4] 2> 1568473 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1568475 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1568475 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1568475 INFO (SUITE-SimpleFacetsTest-seed#[4883D07AC2D07A83]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1568476 INFO (coreCloseExecutor-7689-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7f192d39
[junit4] 2> 1568477 INFO (coreCloseExecutor-7689-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2132356409
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J1/temp/solr.request.SimpleFacetsTest_4883D07AC2D07A83-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {trait_s=PostingsFormat(name=Memory), zerolen_s=BlockTreeOrds(blocksize=128), ignore_s=PostingsFormat(name=Memory), tt_s1=FST50, multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), subject=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), hotel_s1=BlockTreeOrds(blocksize=128), t_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), foo_s=PostingsFormat(name=Memory), title=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), title_lettertok=BlockTreeOrds(blocksize=128), bday_drf=BlockTreeOrds(blocksize=128), contains_group_s1=PostingsFormat(name=Memory), title_stringNoNorms=PostingsFormat(name=Memory), title_stemmed=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), name=BlockTreeOrds(blocksize=128), contains_s1=PostingsFormat(name=Memory), a_drf=PostingsFormat(name=Memory), airport_s1=PostingsFormat(name=Memory), myfield_s=PostingsFormat(name=Memory), id=BlockTreeOrds(blocksize=128), text=FST50, t_s1=FST50}, docValues:{range_facet_mv_f_f=DocValuesFormat(name=Lucene70), range_facet_l_dv=DocValuesFormat(name=Lucene70), range_facet_dt_dv=DocValuesFormat(name=Direct), a_tdt=DocValuesFormat(name=Memory), intDefault=DocValuesFormat(name=Memory), range_facet_f1_dv=DocValuesFormat(name=Asserting), range_facet_l=DocValuesFormat(name=Memory), range_facet_d_dv=DocValuesFormat(name=Lucene70), range_facet_mv_f=DocValuesFormat(name=Memory), range_facet_f=DocValuesFormat(name=Asserting), duration_i1=DocValuesFormat(name=Direct), bday=DocValuesFormat(name=Memory), _version_=DocValuesFormat(name=Memory), range_facet_d=DocValuesFormat(name=Memory), id_i1=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Memory), intDvoDefault=DocValuesFormat(name=Direct), range_facet_f_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Memory), range_facet_f1=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=735, maxMBSortInHeap=6.768165100670086, sim=RandomSimilarity(queryNorm=false): {}, locale=th-TH, timezone=Jamaica
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=3,threads=1,free=145790496,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestDocSet, TestSearchPerf, ZkSolrClientTest, TestJsonRequest, TestSolrIndexConfig, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestPartialUpdateDeduplication, BasicDistributedZk2Test, StressHdfsTest, TestDynamicFieldCollectionResource, TestConfigSetImmutable, TestRTimerTree, TestCollectionAPIs, TestPayloadScoreQParserPlugin, TestStressLucene, TestBadConfig, MoveReplicaHDFSTest, CollectionsAPIDistributedZkTest, HdfsCollectionsAPIDistributedZkTest, UninvertDocValuesMergePolicyTest, TestSolrCloudWithDelegationTokens, TestLockTree, TestSystemIdResolver, CheckHdfsIndexTest, TestSolrCloudWithHadoopAuthPlugin, MBeansHandlerTest, TestCoreAdminApis, HLLSerializationTest, CollectionsAPIAsyncDistributedZkTest, SpellPossibilityIteratorTest, TestFastWriter, TestSortByMinMaxFunction, DistributedQueryComponentOptimizationTest, DistributedTermsComponentTest, TestPostingsSolrHighlighter, LoggingHandlerTest, LukeRequestHandlerTest, SolrCloudExampleTest, TestOnReconnectListenerSupport, TestRestManager, CdcrReplicationDistributedZkTest, AutoScalingHandlerTest, TestAuthenticationFramework, SampleTest, BigEndianAscendingWordSerializerTest, CurrencyFieldTypeTest, ConcurrentDeleteAndCreateCollectionTest, TestFreeTextSuggestions, OpenExchangeRatesOrgProviderTest, SuggesterTSTTest, TestDistributedGrouping, TestSQLHandlerNonCloud, TestCollationFieldDocValues, TestSolr4Spatial2, SolrJmxReporterTest, TestManagedSchemaThreadSafety, TestExactSharedStatsCache, TestRebalanceLeaders, TestBackupRepositoryFactory, TestRealTimeGet, ConfigureRecoveryStrategyTest, TestImplicitCoreProperties, TestBulkSchemaConcurrent, SolrCoreMetricManagerTest, TestFiltering, DistributedFacetPivotWhiteBoxTest, TestSchemaManager, TestInitParams, TestQueryUtils, TestFastLRUCache, TestFastOutputStream, MoveReplicaTest, ReplicationFactorTest, SpatialHeatmapFacetsTest, TestShortCircuitedRequests, TestNumericRangeQuery32, HdfsRestartWhileUpdatingTest, UpdateRequestProcessorFactoryTest, TestTlogReplica, TriLevelCompositeIdRoutingTest, DistributedQueryElevationComponentTest, TestSolrCoreSnapshots, UpdateParamsTest, TestManagedSynonymGraphFilterFactory, TestDistributedMissingSort, SolrPluginUtilsTest, TestConfig, TestCustomStream, TestSerializedLuceneMatchVersion, TestRecovery, NodeMutatorTest, TestMaxTokenLenTokenizer, TestConfigSets, PeerSyncWithIndexFingerprintCachingTest, DocValuesMissingTest, TestRandomFaceting, LeaderFailoverAfterPartitionTest, TestFieldCache, BlockJoinFacetSimpleTest, SolrIndexConfigTest, TestRuleBasedAuthorizationPlugin, TestInitQParser, UUIDUpdateProcessorFallbackTest, PluginInfoTest, TestDocBasedVersionConstraints, TestTolerantUpdateProcessorRandomCloud, TestCrossCoreJoin, TestExportWriter, TestGraphMLResponseWriter, TestDocumentBuilder, EchoParamsTest, TestXmlQParser, TestCollationField, ActionThrottleTest, ImplicitSnitchTest, TestRequestForwarding, HdfsBasicDistributedZk2Test, TestSolrDeletionPolicy1, SuggestComponentTest, HdfsThreadLeakTest, TestReplicationHandlerBackup, SparseHLLTest, TestNumericTerms64, TestNumericTokenStream, ClassificationUpdateProcessorTest, TestLegacyFieldCache, TestConfigSetsAPIExclusivity, SimpleMLTQParserTest, JvmMetricsTest, TestCloudRecovery, TestSolr4Spatial, DeleteStatusTest, TestSolrJ, ConnectionManagerTest, TestRandomFlRTGCloud, TestLegacyFieldReuse, TestRTGBase, TestUnifiedSolrHighlighter, PeerSyncReplicationTest, TestValueSourceCache, VersionInfoTest, SortSpecParsingTest, TestCloudPivotFacet, DistributedExpandComponentTest, SecurityConfHandlerTest, TestHdfsUpdateLog, SearchHandlerTest, TestUseDocValuesAsStored, BaseCdcrDistributedZkTest, TestReRankQParserPlugin, ChaosMonkeySafeLeaderTest, FacetPivotSmallTest, TestLeaderInitiatedRecoveryThread, TestReload, DistributedQueueTest, SpellCheckComponentTest, TestSolrQueryParser, TestOverriddenPrefixQueryForCustomFieldType, SuggesterFSTTest, TestStressUserVersions, TestSolrCloudWithSecureImpersonation, DeleteLastCustomShardedReplicaTest, IndexSchemaRuntimeFieldTest, AssignTest, TestSchemaSimilarityResource, DistributedFacetPivotLargeTest, RulesTest, DocValuesMultiTest, TestSolrXml, TestSuggestSpellingConverter, SpellCheckCollatorTest, SaslZkACLProviderTest, AtomicUpdateProcessorFactoryTest, TestJoin, BadIndexSchemaTest, TestBinaryResponseWriter, SpellingQueryConverterTest, TestZkChroot, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, ClusterStateUpdateTest, LeaderElectionTest, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, AutoCommitTest, TestFaceting, TermVectorComponentDistributedTest, TestStressReorder, HardAutoCommitTest, TestRangeQuery, SimpleFacetsTest]
[junit4] Completed [353/728 (1!)] on J1 in 1.74s, 44 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 44534 lines...]