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 2019/08/06 02:36:17 UTC
[JENKINS] Lucene-Solr-8.2-Linux (32bit/jdk1.8.0_201) - Build # 525
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.2-Linux/525/
Java: 32bit/jdk1.8.0_201 -server -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI
Error Message:
should be a routed alias
Stack Trace:
java.lang.AssertionError: should be a routed alias
at __randomizedtesting.SeedInfo.seed([921035B7799D9533:8DC7A99B0A966C78]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI(AliasIntegrationTest.java:315)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13308 lines...]
[junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/init-core-data-001
[junit4] 2> 83552 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001
[junit4] 2> 83552 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 83554 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 83554 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 83654 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:42303
[junit4] 2> 83654 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:42303
[junit4] 2> 83654 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 42303
[junit4] 2> 83656 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 83657 WARN (NIOWorkerThread-2) [ ] o.a.z.s.NIOServerCnxn Exception causing close of session 0x0: ZooKeeperServer not running
[junit4] 2> 85137 INFO (zkConnectionManagerCallback-267-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 85137 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 85145 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 85148 INFO (zkConnectionManagerCallback-269-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 85148 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 85153 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 85160 INFO (zkConnectionManagerCallback-271-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 85160 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 85266 WARN (jetty-launcher-272-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 85266 WARN (jetty-launcher-272-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 85266 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 85266 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 85266 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 85266 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 85266 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 1.8.0_201-b09
[junit4] 2> 85266 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 1.8.0_201-b09
[junit4] 2> 85272 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 85272 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 85272 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 85272 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 85272 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 85272 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 85273 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a5c0bf{/solr,null,AVAILABLE}
[junit4] 2> 85273 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e56f46{/solr,null,AVAILABLE}
[junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@15f8886{SSL,[ssl, http/1.1]}{127.0.0.1:38485}
[junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.e.j.s.Server Started @85329ms
[junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38485}
[junit4] 2> 85274 ERROR (jetty-launcher-272-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.1
[junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 85274 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-08-06T01:27:31.619Z
[junit4] 2> 85281 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 85281 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@bea459{SSL,[ssl, http/1.1]}{127.0.0.1:34659}
[junit4] 2> 85281 INFO (jetty-launcher-272-thread-1) [ ] o.e.j.s.Server Started @85337ms
[junit4] 2> 85281 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34659}
[junit4] 2> 85282 ERROR (jetty-launcher-272-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 85282 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 85282 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.2.1
[junit4] 2> 85282 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 85282 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 85282 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-08-06T01:27:31.627Z
[junit4] 2> 85284 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 85289 INFO (zkConnectionManagerCallback-274-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 85289 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 85293 INFO (zkConnectionManagerCallback-276-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 85293 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 85294 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 85294 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 85770 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 85770 WARN (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 85771 WARN (jetty-launcher-272-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@19a3a14[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 85771 WARN (jetty-launcher-272-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@19a3a14[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 85775 WARN (jetty-launcher-272-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 85776 WARN (jetty-launcher-272-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@155c782[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 85776 WARN (jetty-launcher-272-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@155c782[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 85777 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42303/solr
[junit4] 2> 85781 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 85783 INFO (zkConnectionManagerCallback-284-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 85783 INFO (jetty-launcher-272-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 85886 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 85904 INFO (zkConnectionManagerCallback-286-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 85904 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 85989 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38485_solr
[junit4] 2> 85989 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.Overseer Overseer (id=72067346309447686-127.0.0.1:38485_solr-n_0000000000) starting
[junit4] 2> 86001 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 86005 INFO (zkConnectionManagerCallback-293-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 86005 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 86008 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42303/solr ready
[junit4] 2> 86023 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:38485_solr
[junit4] 2> 86029 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38485_solr
[junit4] 2> 86041 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 86045 INFO (zkCallback-292-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 86088 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 86175 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38485.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236
[junit4] 2> 86186 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38485.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236
[junit4] 2> 86186 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38485.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236
[junit4] 2> 86187 INFO (jetty-launcher-272-thread-2) [n:127.0.0.1:38485_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node2/.
[junit4] 2> 86388 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 86388 WARN (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 86394 WARN (jetty-launcher-272-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@43dc03[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 86394 WARN (jetty-launcher-272-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@43dc03[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 86398 WARN (jetty-launcher-272-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 86398 WARN (jetty-launcher-272-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@40b7fa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 86398 WARN (jetty-launcher-272-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@40b7fa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 86399 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42303/solr
[junit4] 2> 86401 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 86402 INFO (zkConnectionManagerCallback-300-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 86402 INFO (jetty-launcher-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 86504 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 86506 INFO (zkConnectionManagerCallback-302-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 86506 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 86509 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 86512 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:34659_solr as DOWN
[junit4] 2> 86513 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 86513 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34659_solr
[junit4] 2> 86518 INFO (zkCallback-292-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 86518 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 86522 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 86525 INFO (zkConnectionManagerCallback-309-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 86525 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 86526 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 86527 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 86529 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42303/solr ready
[junit4] 2> 86549 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 86572 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236
[junit4] 2> 86582 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236
[junit4] 2> 86582 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236
[junit4] 2> 86583 INFO (jetty-launcher-272-thread-1) [n:127.0.0.1:34659_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/.
[junit4] 2> 86617 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 86619 INFO (zkConnectionManagerCallback-315-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 86619 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 86620 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 86621 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42303/solr ready
[junit4] 2> 86750 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 86753 INFO (OverseerThreadFactory-304-thread-1-processing-n:127.0.0.1:38485_solr) [n:127.0.0.1:38485_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1meta
[junit4] 2> 86866 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1meta_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:38485/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 86867 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1meta_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:34659/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 87078 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 87078 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 87138 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1meta_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 88100 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1
[junit4] 2> 88108 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1meta_shard1_replica_n1] Schema name=minimal
[junit4] 2> 88110 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 88110 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard1_replica_n1' using configuration from collection collection1meta, trusted=true
[junit4] 2> 88111 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38485.solr.core.collection1meta.shard1.replica_n1' (registry 'solr.core.collection1meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236
[junit4] 2> 88111 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1meta_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node2/collection1meta_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node2/./collection1meta_shard1_replica_n1/data/]
[junit4] 2> 88153 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1
[junit4] 2> 88163 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.IndexSchema [collection1meta_shard2_replica_n2] Schema name=minimal
[junit4] 2> 88164 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 88164 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 88166 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 88166 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 88167 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bd191[collection1meta_shard1_replica_n1] main]
[junit4] 2> 88168 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 88168 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard2_replica_n2' using configuration from collection collection1meta, trusted=true
[junit4] 2> 88169 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.core.collection1meta.shard2.replica_n2' (registry 'solr.core.collection1meta.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236
[junit4] 2> 88169 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1meta_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/collection1meta_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/./collection1meta_shard2_replica_n2/data/]
[junit4] 2> 88173 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 88173 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 88174 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 88174 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1641078959132114944
[junit4] 2> 88179 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 88180 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard1
[junit4] 2> 88181 INFO (searcherExecutor-316-thread-1-processing-n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 c:collection1meta s:shard1 r:core_node3) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] Registered new searcher Searcher@1bd191[collection1meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38485/solr/collection1meta_shard1_replica_n1/
[junit4] 2> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:38485/solr/collection1meta_shard1_replica_n1/ has no replicas
[junit4] 2> 88184 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard1/leader after winning as /collections/collection1meta/leader_elect/shard1/election/72067346309447686-core_node3-n_0000000000
[junit4] 2> 88186 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38485/solr/collection1meta_shard1_replica_n1/ shard1
[junit4] 2> 88253 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 88253 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 88254 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 88255 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 88256 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@c73f40[collection1meta_shard2_replica_n2] main]
[junit4] 2> 88258 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 88258 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 88258 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 88259 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1641078959221243904
[junit4] 2> 88262 INFO (searcherExecutor-321-thread-1-processing-n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 c:collection1meta s:shard2 r:core_node4) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1meta_shard2_replica_n2] Registered new searcher Searcher@c73f40[collection1meta_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 88266 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 88266 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard2
[junit4] 2> 88269 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 88269 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 88269 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34659/solr/collection1meta_shard2_replica_n2/
[junit4] 2> 88269 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 88270 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SyncStrategy https://127.0.0.1:34659/solr/collection1meta_shard2_replica_n2/ has no replicas
[junit4] 2> 88270 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard2/leader after winning as /collections/collection1meta/leader_elect/shard2/election/72067346309447689-core_node4-n_0000000000
[junit4] 2> 88271 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34659/solr/collection1meta_shard2_replica_n2/ shard2
[junit4] 2> 88373 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 88373 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 88374 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 88375 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 88480 INFO (zkCallback-285-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 88480 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 88480 INFO (zkCallback-301-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 88480 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 88627 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1meta_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1489
[junit4] 2> 88632 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1554
[junit4] 2> 88638 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 88638 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=1887
[junit4] 2> 88641 INFO (qtp10842137-931) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection2meta&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 88643 INFO (OverseerThreadFactory-304-thread-2-processing-n:127.0.0.1:38485_solr) [n:127.0.0.1:38485_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection2meta
[junit4] 2> 88643 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_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> 88861 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection2meta",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection2meta_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:34659/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 89064 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection2meta_shard1_replica_n1&action=CREATE&numShards=1&collection=collection2meta&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 90083 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1
[junit4] 2> 90090 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection2meta_shard1_replica_n1] Schema name=minimal
[junit4] 2> 90092 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 90092 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection2meta_shard1_replica_n1' using configuration from collection collection2meta, trusted=true
[junit4] 2> 90093 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.core.collection2meta.shard1.replica_n1' (registry 'solr.core.collection2meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236
[junit4] 2> 90094 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection2meta_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/collection2meta_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/./collection2meta_shard1_replica_n1/data/]
[junit4] 2> 90148 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 90149 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 90150 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 90150 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 90152 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@63aff[collection2meta_shard1_replica_n1] main]
[junit4] 2> 90154 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 90154 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 90154 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 90154 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1641078961208295424
[junit4] 2> 90158 INFO (searcherExecutor-326-thread-1-processing-n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 c:collection2meta s:shard1 r:core_node2) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection2meta_shard1_replica_n1] Registered new searcher Searcher@63aff[collection2meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 90175 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection2meta/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 90176 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2meta/leaders/shard1
[junit4] 2> 90186 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 90187 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 90187 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34659/solr/collection2meta_shard1_replica_n1/
[junit4] 2> 90187 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 90187 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:34659/solr/collection2meta_shard1_replica_n1/ has no replicas
[junit4] 2> 90187 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2meta/leaders/shard1/leader after winning as /collections/collection2meta/leader_elect/shard1/election/72067346309447689-core_node2-n_0000000000
[junit4] 2> 90189 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34659/solr/collection2meta_shard1_replica_n1/ shard1
[junit4] 2> 90291 INFO (zkCallback-301-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90291 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90298 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 90307 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection2meta s:shard1 r:core_node2 x:collection2meta_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection2meta_shard1_replica_n1&action=CREATE&numShards=1&collection=collection2meta&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1243
[junit4] 2> 90311 INFO (qtp10842137-931) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 90400 INFO (zkCallback-285-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90400 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90400 INFO (zkCallback-301-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90401 INFO (qtp10842137-931) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection2meta&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1760
[junit4] 2> 90418 INFO (zkCallback-301-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90442 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 90443 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 90443 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 2 expected replica count: 2
[junit4] 2> 90443 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 90444 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 90444 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 1 expected replica count: 1
[junit4] 2> 90451 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :aliasprop with params name=testClusterStateProviderAPI&action=ALIASPROP&property.foo=baz&property.bar=bam&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 90472 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 90475 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=testClusterStateProviderAPI&action=ALIASPROP&property.foo=baz&property.bar=bam&wt=javabin&version=2} status=0 QTime=23
[junit4] 2> 90477 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :aliasprop with params property.router.foo=baz&name=testClusterStateProviderAPI&action=ALIASPROP&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 90488 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 90491 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={property.router.foo=baz&name=testClusterStateProviderAPI&action=ALIASPROP&wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 90494 INFO (TEST-AliasIntegrationTest.testClusterStateProviderAPI-seed#[921035B7799D9533]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 90498 INFO (qtp10842137-931) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=collection2meta&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 90529 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 90537 INFO (OverseerThreadFactory-304-thread-5-processing-n:127.0.0.1:38485_solr) [n:127.0.0.1:38485_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 90542 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection2meta.shard1.replica_n1, tag=null
[junit4] 2> 90542 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@16ca33b: rootName = solr_34659, domain = solr.core.collection2meta.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection2meta.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@79ec9e
[junit4] 2> 90560 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection2meta_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@766d5b
[junit4] 2> 90560 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection2meta.shard1.replica_n1, tag=766d5b
[junit4] 2> 90560 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection2meta.shard1.leader, tag=766d5b
[junit4] 2> 90585 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection2meta/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 90587 INFO (qtp21250618-930) [n:127.0.0.1:34659_solr x:collection2meta_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection2meta_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=48
[junit4] 2> 90693 INFO (zkCallback-285-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90693 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90693 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90693 INFO (zkCallback-301-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90693 INFO (zkCallback-301-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection2meta/state.json] for collection [collection2meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90706 INFO (qtp10842137-931) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=collection2meta&action=DELETE&wt=javabin&version=2} status=0 QTime=207
[junit4] 2> 90707 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=collection1meta&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 90710 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 90710 INFO (OverseerThreadFactory-304-thread-5-processing-n:127.0.0.1:38485_solr) [n:127.0.0.1:38485_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 90724 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard1.replica_n1, tag=null
[junit4] 2> 90724 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1bab1ed: rootName = solr_38485, domain = solr.core.collection1meta.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.collection1meta.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@b940d1
[junit4] 2> 90724 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard2.replica_n2, tag=null
[junit4] 2> 90724 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@8a6d90: rootName = solr_34659, domain = solr.core.collection1meta.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.collection1meta.shard2.replica_n2 / com.codahale.metrics.MetricRegistry@18340fd
[junit4] 2> 90746 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1meta_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@354f2c
[junit4] 2> 90746 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard2.replica_n2, tag=354f2c
[junit4] 2> 90746 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1meta.shard2.leader, tag=354f2c
[junit4] 2> 90749 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={}, version=1}
[junit4] 2> 90750 INFO (qtp21250618-932) [n:127.0.0.1:34659_solr x:collection1meta_shard2_replica_n2 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection1meta_shard2_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=26
[junit4] 2> 90755 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@17c37d4
[junit4] 2> 90755 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1meta.shard1.replica_n1, tag=17c37d4
[junit4] 2> 90755 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1meta.shard1.leader, tag=17c37d4
[junit4] 2> 90765 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 90768 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=collection1meta_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=45
[junit4] 2> 90871 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90871 INFO (zkCallback-301-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90871 INFO (zkCallback-285-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90871 INFO (zkCallback-301-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 90878 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=collection1meta&action=DELETE&wt=javabin&version=2} status=0 QTime=171
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AliasIntegrationTest -Dtests.method=testClusterStateProviderAPI -Dtests.seed=921035B7799D9533 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=de -Dtests.timezone=Asia/Katmandu -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 4.27s J1 | AliasIntegrationTest.testClusterStateProviderAPI <<<
[junit4] > Throwable #1: java.lang.AssertionError: should be a routed alias
[junit4] > at __randomizedtesting.SeedInfo.seed([921035B7799D9533:8DC7A99B0A966C78]:0)
[junit4] > at org.apache.solr.cloud.AliasIntegrationTest.testClusterStateProviderAPI(AliasIntegrationTest.java:315)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 90949 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 90966 INFO (qtp10842137-935) [n:127.0.0.1:38485_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=16
[junit4] 2> 90969 INFO (qtp10842137-931) [n:127.0.0.1:38485_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1meta&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 90981 INFO (OverseerThreadFactory-304-thread-5-processing-n:127.0.0.1:38485_solr) [n:127.0.0.1:38485_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1meta
[junit4] 2> 90981 INFO (OverseerCollectionConfigSetProcessor-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 91197 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1meta_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:34659/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 91199 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1meta",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1meta_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:38485/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 91401 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr x:collection1meta_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1meta_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1meta&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 91401 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr x:collection1meta_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1meta_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1meta&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 92426 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1
[junit4] 2> 92426 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1
[junit4] 2> 92434 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1meta_shard1_replica_n1] Schema name=minimal
[junit4] 2> 92524 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.IndexSchema [collection1meta_shard2_replica_n2] Schema name=minimal
[junit4] 2> 92524 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 92524 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard1_replica_n1' using configuration from collection collection1meta, trusted=true
[junit4] 2> 92525 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34659.solr.core.collection1meta.shard1.replica_n1' (registry 'solr.core.collection1meta.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236
[junit4] 2> 92525 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1meta_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/collection1meta_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node1/./collection1meta_shard1_replica_n1/data/]
[junit4] 2> 92526 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 92526 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1meta_shard2_replica_n2' using configuration from collection collection1meta, trusted=true
[junit4] 2> 92526 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38485.solr.core.collection1meta.shard2.replica_n2' (registry 'solr.core.collection1meta.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@19c0236
[junit4] 2> 92526 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1meta_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node2/collection1meta_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001/tempDir-001/node2/./collection1meta_shard2_replica_n2/data/]
[junit4] 2> 92606 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 92607 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 92608 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 92608 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 92611 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c13338[collection1meta_shard1_replica_n1] main]
[junit4] 2> 92612 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 92612 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 92613 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 92613 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1641078963786743808
[junit4] 2> 92621 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 92621 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 92622 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 92622 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 92624 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d1905f[collection1meta_shard2_replica_n2] main]
[junit4] 2> 92625 INFO (searcherExecutor-331-thread-1-processing-n:127.0.0.1:34659_solr x:collection1meta_shard1_replica_n1 c:collection1meta s:shard1 r:core_node3) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1meta_shard1_replica_n1] Registered new searcher Searcher@1c13338[collection1meta_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 92627 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 92627 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard1
[junit4] 2> 92628 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 92629 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 92629 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 92629 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1641078963803521024
[junit4] 2> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34659/solr/collection1meta_shard1_replica_n1/
[junit4] 2> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:34659/solr/collection1meta_shard1_replica_n1/ has no replicas
[junit4] 2> 92631 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard1/leader after winning as /collections/collection1meta/leader_elect/shard1/election/72067346309447689-core_node3-n_0000000000
[junit4] 2> 92638 INFO (searcherExecutor-332-thread-1-processing-n:127.0.0.1:38485_solr x:collection1meta_shard2_replica_n2 c:collection1meta s:shard2 r:core_node4) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1meta_shard2_replica_n2] Registered new searcher Searcher@1d1905f[collection1meta_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 92647 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1meta/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 92647 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1meta/leaders/shard2
[junit4] 2> 92649 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34659/solr/collection1meta_shard1_replica_n1/ shard1
[junit4] 2> 92651 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 92651 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 92651 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38485/solr/collection1meta_shard2_replica_n2/
[junit4] 2> 92652 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 92652 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.SyncStrategy https://127.0.0.1:38485/solr/collection1meta_shard2_replica_n2/ has no replicas
[junit4] 2> 92652 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1meta/leaders/shard2/leader after winning as /collections/collection1meta/leader_elect/shard2/election/72067346309447686-core_node4-n_0000000000
[junit4] 2> 92653 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38485/solr/collection1meta_shard2_replica_n2/ shard2
[junit4] 2> 92755 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 92755 INFO (zkCallback-285-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 92756 INFO (qtp10842137-933) [n:127.0.0.1:38485_solr c:collection1meta s:shard2 r:core_node4 x:collection1meta_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 92761 INFO (zkCallback-301-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 92761 INFO (zkCallback-301-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 92762 INFO (qtp21250618-934) [n:127.0.0.1:34659_solr c:collection1meta s:shard1 r:core_node3 x:collection1meta_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 92865 INFO (zkCallback-285-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 92865 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 92865 INFO (zkCallback-285-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1meta/state.json] for collection [collection1meta] has occurred - updating... (live nodes size: [2])
[junit4] 2> 92873 INFO (zkCallback-301-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:Sy
[...truncated too long message...]
d2
[junit4] 2> 143801 INFO (jetty-closer-390-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 143801 INFO (jetty-closer-390-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@19f47c4: rootName = solr_38485, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@134823a
[junit4] 2> 143801 INFO (jetty-closer-390-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 143841 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 143841 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2378c0: rootName = solr_34659, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@13592bb
[junit4] 2> 143846 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 143846 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1232691: rootName = solr_34659, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@16407d2
[junit4] 2> 143848 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 143848 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@49744a: rootName = solr_34659, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@134823a
[junit4] 2> 143848 INFO (jetty-closer-390-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 143864 INFO (closeThreadPool-397-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72067346309447686-127.0.0.1:38485_solr-n_0000000000) closing
[junit4] 2> 143910 INFO (OverseerStateUpdate-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [n:127.0.0.1:38485_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:38485_solr
[junit4] 2> 143953 INFO (OverseerAutoScalingTriggerThread-72067346309447686-127.0.0.1:38485_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 143954 INFO (closeThreadPool-397-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72067346309447686-127.0.0.1:38485_solr-n_0000000000) closing
[junit4] 2> 144056 INFO (jetty-closer-390-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72067346309447686-127.0.0.1:38485_solr-n_0000000000) closing
[junit4] 2> 144061 INFO (jetty-closer-390-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@15f8886{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 144061 INFO (jetty-closer-390-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1e56f46{/solr,null,UNAVAILABLE}
[junit4] 2> 144061 INFO (jetty-closer-390-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 144157 INFO (jetty-closer-390-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@bea459{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 144157 INFO (jetty-closer-390-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1a5c0bf{/solr,null,UNAVAILABLE}
[junit4] 2> 144158 INFO (jetty-closer-390-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 144158 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 144369 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 28 /solr/collections/collection1meta/state.json
[junit4] 2> 22 /solr/aliases.json
[junit4] 2> 21 /solr/collections/collection2meta/state.json
[junit4] 2> 18 /solr/configs/conf
[junit4] 2> 15 /solr/collections/collection1meta/terms/shard1
[junit4] 2> 14 /solr/collections/collection1meta/terms/shard2
[junit4] 2> 13 /solr/collections/collection2meta/terms/shard1
[junit4] 2> 6 /solr/collections/testErrorChecks-collection/state.json
[junit4] 2> 4 /solr/collections/collection_two/state.json
[junit4] 2> 4 /solr/collections/collection_one/terms/shard1
[junit4] 2> 4 /solr/collections/collection_new/state.json
[junit4] 2> 4 /solr/collections/collection_new/terms/shard1
[junit4] 2> 4 /solr/collections/collection2/terms/shard1
[junit4] 2> 4 /solr/collections/collection_old/terms/shard2
[junit4] 2> 4 /solr/collections/collection_old/terms/shard1
[junit4] 2> 4 /solr/collections/collection1/terms/shard1
[junit4] 2> 3 /solr/collections/collection1/state.json
[junit4] 2> 3 /solr/collections/collection_two/terms/shard1
[junit4] 2> 3 /solr/collections/collection_old/state.json
[junit4] 2> 3 /solr/collections/testErrorChecks-collection/terms/shard1
[junit4] 2> 3 /solr/collections/testErrorChecks-collection/terms/shard2
[junit4] 2> 3 /solr/collections/collection1/terms/shard2
[junit4] 2> 2 /solr/collections/collection_old/collectionprops.json
[junit4] 2> 2 /solr/collections/collection_one/terms/shard2
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/collection_one/collectionprops.json
[junit4] 2> 2 /solr/collections/collection1/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 475 /solr/aliases.json
[junit4] 2> 96 /solr/collections/collection1meta/state.json
[junit4] 2> 63 /solr/collections/collection2meta/state.json
[junit4] 2> 27 /solr/collections/collection_one/state.json
[junit4] 2> 27 /solr/collections/collection_old/state.json
[junit4] 2> 26 /solr/collections/collection1/state.json
[junit4] 2> 22 /solr/clusterprops.json
[junit4] 2> 22 /solr/clusterstate.json
[junit4] 2> 20 /solr/collections/testErrorChecks-collection/state.json
[junit4] 2> 15 /solr/collections/collection_new/state.json
[junit4] 2> 15 /solr/collections/collection_two/state.json
[junit4] 2> 9 /solr/collections/collection2/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 299 /solr/collections
[junit4] 2> 263 /solr/overseer/queue
[junit4] 2> 233 /solr/overseer/collection-queue-work
[junit4] 2> 27 /solr/live_nodes
[junit4] 2>
[junit4] 2> 144386 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:42303
[junit4] 2> 144386 INFO (SUITE-AliasIntegrationTest-seed#[921035B7799D9533]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 42303
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.AliasIntegrationTest_921035B7799D9533-001
[junit4] 2> Aug 06, 2019 1:28:30 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {a_t=PostingsFormat(name=Asserting), _root_=PostingsFormat(name=Asserting), id=FSTOrd50}, docValues:{}, maxPointsInLeafNode=1536, maxMBSortInHeap=7.960667339327415, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3d1fd), locale=de, timezone=Asia/Katmandu
[junit4] 2> NOTE: Linux 5.0.0-23-generic i386/Oracle Corporation 1.8.0_201 (32-bit)/cpus=8,threads=1,free=131156640,total=268435456
[junit4] 2> NOTE: All tests run in this JVM: [DistributedFacetPivotLargeTest, LoggingHandlerTest, HdfsNNFailoverTest, RequestHandlersTest, TestIndexingPerformance, DirectSolrConnectionTest, TestDocTermOrdsUninvertLimit, TestAuthorizationFramework, TestSQLHandlerNonCloud, StatelessScriptUpdateProcessorFactoryTest, TestManagedSynonymFilterFactory, TestLegacyNumericUtils, AliasIntegrationTest]
[junit4] Completed [33/867 (1!)] on J1 in 61.51s, 9 tests, 1 failure <<< FAILURES!
[...truncated 50734 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.2-Linux/525/consoleText
[repro] Revision: 7b0c7cada62a361aa9b00ecedb804e890f1724d4
[repro] Ant options: "-Dargs=-server -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=AliasIntegrationTest -Dtests.method=testClusterStateProviderAPI -Dtests.seed=921035B7799D9533 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=de -Dtests.timezone=Asia/Katmandu -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] AliasIntegrationTest
[repro] ant compile-test
[...truncated 3577 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.AliasIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseG1GC" -Dtests.seed=921035B7799D9533 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=de -Dtests.timezone=Asia/Katmandu -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 110 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.AliasIntegrationTest
[repro] Exiting with code 0
[...truncated 43 lines...]
[JENKINS] Lucene-Solr-8.2-Linux (64bit/jdk-12.0.1) - Build # 526 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.2-Linux/526/
Java: 64bit/jdk-12.0.1 -XX:-UseCompressedOops -XX:+UseParallelGC
4 tests failed.
FAILED: org.apache.solr.search.mlt.SimpleMLTQParserTest.doTest
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([A9C4229C0DE00686:E809A38605B153F]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:947)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:907)
at org.apache.solr.search.mlt.SimpleMLTQParserTest.doTest(SimpleMLTQParserTest.java:82)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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.base/java.lang.Thread.run(Thread.java:835)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result/doc[1]/str[@name='id'][.='13']
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">50</int></lst><result name="response" numFound="16" start="0"><doc><str name="id">16</str><arr name="range_facet_l"><long>16</long></arr><int name="id_i1">16</int><arr name="range_facet_l_dv"><long>16</long></arr><arr name="range_facet_i_dv"><int>16</int></arr><arr name="lowerfilt"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:27.394Z</date><long name="_version_">1641116385825259520</long><int name="intDvoDefault">42</int></doc><doc><str name="id">18</str><arr name="range_facet_l"><long>18</long></arr><int name="id_i1">18</int><arr name="range_facet_l_dv"><long>18</long></arr><arr name="range_facet_i_dv"><int>18</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:27.420Z</date><long name="_version_">1641116385851473920</long><int name="intDvoDefault">42</int></doc><doc><str name="id">19</str><arr name="range_facet_l"><long>19</long></arr><int name="id_i1">19</int><arr name="range_facet_l_dv"><long>19</long></arr><arr name="range_facet_i_dv"><int>19</int></arr><arr name="lowerfilt"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:27.439Z</date><long name="_version_">1641116385887125504</long><int name="intDvoDefault">42</int></doc><doc><str name="id">20</str><arr name="range_facet_l"><long>20</long></arr><int name="id_i1">20</int><arr name="range_facet_l_dv"><long>20</long></arr><arr name="range_facet_i_dv"><int>20</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:27.456Z</date><long name="_version_">1641116385894465536</long><int name="intDvoDefault">42</int></doc><doc><str name="id">21</str><arr name="range_facet_l"><long>21</long></arr><int name="id_i1">21</int><arr name="range_facet_l_dv"><long>21</long></arr><arr name="range_facet_i_dv"><int>21</int></arr><arr name="lowerfilt"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:27.463Z</date><long name="_version_">1641116385898659840</long><int name="intDvoDefault">42</int></doc><doc><str name="id">22</str><arr name="range_facet_l"><long>22</long></arr><int name="id_i1">22</int><arr name="range_facet_l_dv"><long>22</long></arr><arr name="range_facet_i_dv"><int>22</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:27.467Z</date><long name="_version_">1641116385900756992</long><int name="intDvoDefault">42</int></doc><doc><str name="id">23</str><arr name="range_facet_l"><long>23</long></arr><int name="id_i1">23</int><arr name="range_facet_l_dv"><long>23</long></arr><arr name="range_facet_i_dv"><int>23</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:27.469Z</date><long name="_version_">1641116385903902720</long><int name="intDvoDefault">42</int></doc><doc><str name="id">24</str><arr name="range_facet_l"><long>24</long></arr><int name="id_i1">24</int><arr name="range_facet_l_dv"><long>24</long></arr><arr name="range_facet_i_dv"><int>24</int></arr><arr name="lowerfilt"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:27.471Z</date><long name="_version_">1641116385905999872</long><int name="intDvoDefault">42</int></doc><doc><str name="id">30</str><arr name="range_facet_l"><long>30</long></arr><int name="id_i1">30</int><arr name="range_facet_l_dv"><long>30</long></arr><arr name="range_facet_i_dv"><int>30</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str><str>red green yellow</str></arr><arr name="lowerfilt1"><str>red green yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:27.622Z</date><long name="_version_">1641116386063286272</long><int name="intDvoDefault">42</int></doc><doc><str name="id">31</str><arr name="range_facet_l"><long>31</long></arr><int name="id_i1">31</int><arr name="range_facet_l_dv"><long>31</long></arr><arr name="range_facet_i_dv"><int>31</int></arr><arr name="lowerfilt"><str>The fat red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The fat red fox jumped over the lazy brown dogs.</str><str>green blue yellow</str></arr><arr name="lowerfilt1"><str>green blue yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:27.625Z</date><long name="_version_">1641116386066432000</long><int name="intDvoDefault">42</int></doc></result>
</response>
request was:q={!mlt+qf%3Dlowerfilt}17
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
... 40 more
FAILED: org.apache.solr.search.mlt.SimpleMLTQParserTest.doTest
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([A9C4229C0DE00686:E809A38605B153F]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:947)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:907)
at org.apache.solr.search.mlt.SimpleMLTQParserTest.doTest(SimpleMLTQParserTest.java:82)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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.base/java.lang.Thread.run(Thread.java:835)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result/doc[1]/str[@name='id'][.='13']
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">2</int></lst><result name="response" numFound="16" start="0"><doc><str name="id">16</str><arr name="range_facet_l"><long>16</long></arr><int name="id_i1">16</int><arr name="range_facet_l_dv"><long>16</long></arr><arr name="range_facet_i_dv"><int>16</int></arr><arr name="lowerfilt"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.587Z</date><long name="_version_">1641116389172314112</long><int name="intDvoDefault">42</int></doc><doc><str name="id">18</str><arr name="range_facet_l"><long>18</long></arr><int name="id_i1">18</int><arr name="range_facet_l_dv"><long>18</long></arr><arr name="range_facet_i_dv"><int>18</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.591Z</date><long name="_version_">1641116389176508416</long><int name="intDvoDefault">42</int></doc><doc><str name="id">19</str><arr name="range_facet_l"><long>19</long></arr><int name="id_i1">19</int><arr name="range_facet_l_dv"><long>19</long></arr><arr name="range_facet_i_dv"><int>19</int></arr><arr name="lowerfilt"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.593Z</date><long name="_version_">1641116389178605568</long><int name="intDvoDefault">42</int></doc><doc><str name="id">20</str><arr name="range_facet_l"><long>20</long></arr><int name="id_i1">20</int><arr name="range_facet_l_dv"><long>20</long></arr><arr name="range_facet_i_dv"><int>20</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.594Z</date><long name="_version_">1641116389180702720</long><int name="intDvoDefault">42</int></doc><doc><str name="id">21</str><arr name="range_facet_l"><long>21</long></arr><int name="id_i1">21</int><arr name="range_facet_l_dv"><long>21</long></arr><arr name="range_facet_i_dv"><int>21</int></arr><arr name="lowerfilt"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.596Z</date><long name="_version_">1641116389181751296</long><int name="intDvoDefault">42</int></doc><doc><str name="id">22</str><arr name="range_facet_l"><long>22</long></arr><int name="id_i1">22</int><arr name="range_facet_l_dv"><long>22</long></arr><arr name="range_facet_i_dv"><int>22</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.598Z</date><long name="_version_">1641116389183848448</long><int name="intDvoDefault">42</int></doc><doc><str name="id">23</str><arr name="range_facet_l"><long>23</long></arr><int name="id_i1">23</int><arr name="range_facet_l_dv"><long>23</long></arr><arr name="range_facet_i_dv"><int>23</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.600Z</date><long name="_version_">1641116389185945600</long><int name="intDvoDefault">42</int></doc><doc><str name="id">24</str><arr name="range_facet_l"><long>24</long></arr><int name="id_i1">24</int><arr name="range_facet_l_dv"><long>24</long></arr><arr name="range_facet_i_dv"><int>24</int></arr><arr name="lowerfilt"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.601Z</date><long name="_version_">1641116389188042752</long><int name="intDvoDefault">42</int></doc><doc><str name="id">30</str><arr name="range_facet_l"><long>30</long></arr><int name="id_i1">30</int><arr name="range_facet_l_dv"><long>30</long></arr><arr name="range_facet_i_dv"><int>30</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str><str>red green yellow</str></arr><arr name="lowerfilt1"><str>red green yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.620Z</date><long name="_version_">1641116389206917120</long><int name="intDvoDefault">42</int></doc><doc><str name="id">31</str><arr name="range_facet_l"><long>31</long></arr><int name="id_i1">31</int><arr name="range_facet_l_dv"><long>31</long></arr><arr name="range_facet_i_dv"><int>31</int></arr><arr name="lowerfilt"><str>The fat red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The fat red fox jumped over the lazy brown dogs.</str><str>green blue yellow</str></arr><arr name="lowerfilt1"><str>green blue yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.622Z</date><long name="_version_">1641116389210062848</long><int name="intDvoDefault">42</int></doc></result>
</response>
request was:q={!mlt+qf%3Dlowerfilt}17
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
... 40 more
FAILED: org.apache.solr.search.mlt.SimpleMLTQParserTest.doTest
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([A9C4229C0DE00686:E809A38605B153F]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:947)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:907)
at org.apache.solr.search.mlt.SimpleMLTQParserTest.doTest(SimpleMLTQParserTest.java:82)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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.base/java.lang.Thread.run(Thread.java:835)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result/doc[1]/str[@name='id'][.='13']
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="16" start="0"><doc><str name="id">16</str><arr name="range_facet_l"><long>16</long></arr><int name="id_i1">16</int><arr name="range_facet_l_dv"><long>16</long></arr><arr name="range_facet_i_dv"><int>16</int></arr><arr name="lowerfilt"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.749Z</date><long name="_version_">1641116389343232000</long><int name="intDvoDefault">42</int></doc><doc><str name="id">18</str><arr name="range_facet_l"><long>18</long></arr><int name="id_i1">18</int><arr name="range_facet_l_dv"><long>18</long></arr><arr name="range_facet_i_dv"><int>18</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.757Z</date><long name="_version_">1641116389350572032</long><int name="intDvoDefault">42</int></doc><doc><str name="id">19</str><arr name="range_facet_l"><long>19</long></arr><int name="id_i1">19</int><arr name="range_facet_l_dv"><long>19</long></arr><arr name="range_facet_i_dv"><int>19</int></arr><arr name="lowerfilt"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.764Z</date><long name="_version_">1641116389358960640</long><int name="intDvoDefault">42</int></doc><doc><str name="id">20</str><arr name="range_facet_l"><long>20</long></arr><int name="id_i1">20</int><arr name="range_facet_l_dv"><long>20</long></arr><arr name="range_facet_i_dv"><int>20</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.767Z</date><long name="_version_">1641116389361057792</long><int name="intDvoDefault">42</int></doc><doc><str name="id">21</str><arr name="range_facet_l"><long>21</long></arr><int name="id_i1">21</int><arr name="range_facet_l_dv"><long>21</long></arr><arr name="range_facet_i_dv"><int>21</int></arr><arr name="lowerfilt"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.770Z</date><long name="_version_">1641116389364203520</long><int name="intDvoDefault">42</int></doc><doc><str name="id">22</str><arr name="range_facet_l"><long>22</long></arr><int name="id_i1">22</int><arr name="range_facet_l_dv"><long>22</long></arr><arr name="range_facet_i_dv"><int>22</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.775Z</date><long name="_version_">1641116389369446400</long><int name="intDvoDefault">42</int></doc><doc><str name="id">23</str><arr name="range_facet_l"><long>23</long></arr><int name="id_i1">23</int><arr name="range_facet_l_dv"><long>23</long></arr><arr name="range_facet_i_dv"><int>23</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.777Z</date><long name="_version_">1641116389371543552</long><int name="intDvoDefault">42</int></doc><doc><str name="id">24</str><arr name="range_facet_l"><long>24</long></arr><int name="id_i1">24</int><arr name="range_facet_l_dv"><long>24</long></arr><arr name="range_facet_i_dv"><int>24</int></arr><arr name="lowerfilt"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.779Z</date><long name="_version_">1641116389373640704</long><int name="intDvoDefault">42</int></doc><doc><str name="id">30</str><arr name="range_facet_l"><long>30</long></arr><int name="id_i1">30</int><arr name="range_facet_l_dv"><long>30</long></arr><arr name="range_facet_i_dv"><int>30</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str><str>red green yellow</str></arr><arr name="lowerfilt1"><str>red green yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.797Z</date><long name="_version_">1641116389393563648</long><int name="intDvoDefault">42</int></doc><doc><str name="id">31</str><arr name="range_facet_l"><long>31</long></arr><int name="id_i1">31</int><arr name="range_facet_l_dv"><long>31</long></arr><arr name="range_facet_i_dv"><int>31</int></arr><arr name="lowerfilt"><str>The fat red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The fat red fox jumped over the lazy brown dogs.</str><str>green blue yellow</str></arr><arr name="lowerfilt1"><str>green blue yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.800Z</date><long name="_version_">1641116389395660800</long><int name="intDvoDefault">42</int></doc></result>
</response>
request was:q={!mlt+qf%3Dlowerfilt}17
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
... 40 more
FAILED: org.apache.solr.search.mlt.SimpleMLTQParserTest.doTest
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([A9C4229C0DE00686:E809A38605B153F]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:947)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:907)
at org.apache.solr.search.mlt.SimpleMLTQParserTest.doTest(SimpleMLTQParserTest.java:82)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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.base/java.lang.Thread.run(Thread.java:835)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result/doc[1]/str[@name='id'][.='13']
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="16" start="0"><doc><str name="id">16</str><arr name="range_facet_l"><long>16</long></arr><int name="id_i1">16</int><arr name="range_facet_l_dv"><long>16</long></arr><arr name="range_facet_i_dv"><int>16</int></arr><arr name="lowerfilt"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.547Z</date><long name="_version_">1641115340554371072</long><int name="intDvoDefault">42</int></doc><doc><str name="id">18</str><arr name="range_facet_l"><long>18</long></arr><int name="id_i1">18</int><arr name="range_facet_l_dv"><long>18</long></arr><arr name="range_facet_i_dv"><int>18</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419649</long><int name="intDvoDefault">42</int></doc><doc><str name="id">19</str><arr name="range_facet_l"><long>19</long></arr><int name="id_i1">19</int><arr name="range_facet_l_dv"><long>19</long></arr><arr name="range_facet_i_dv"><int>19</int></arr><arr name="lowerfilt"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419650</long><int name="intDvoDefault">42</int></doc><doc><str name="id">20</str><arr name="range_facet_l"><long>20</long></arr><int name="id_i1">20</int><arr name="range_facet_l_dv"><long>20</long></arr><arr name="range_facet_i_dv"><int>20</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419651</long><int name="intDvoDefault">42</int></doc><doc><str name="id">21</str><arr name="range_facet_l"><long>21</long></arr><int name="id_i1">21</int><arr name="range_facet_l_dv"><long>21</long></arr><arr name="range_facet_i_dv"><int>21</int></arr><arr name="lowerfilt"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419652</long><int name="intDvoDefault">42</int></doc><doc><str name="id">22</str><arr name="range_facet_l"><long>22</long></arr><int name="id_i1">22</int><arr name="range_facet_l_dv"><long>22</long></arr><arr name="range_facet_i_dv"><int>22</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468224</long><int name="intDvoDefault">42</int></doc><doc><str name="id">23</str><arr name="range_facet_l"><long>23</long></arr><int name="id_i1">23</int><arr name="range_facet_l_dv"><long>23</long></arr><arr name="range_facet_i_dv"><int>23</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468225</long><int name="intDvoDefault">42</int></doc><doc><str name="id">24</str><arr name="range_facet_l"><long>24</long></arr><int name="id_i1">24</int><arr name="range_facet_l_dv"><long>24</long></arr><arr name="range_facet_i_dv"><int>24</int></arr><arr name="lowerfilt"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468226</long><int name="intDvoDefault">42</int></doc><doc><str name="id">30</str><arr name="range_facet_l"><long>30</long></arr><int name="id_i1">30</int><arr name="range_facet_l_dv"><long>30</long></arr><arr name="range_facet_i_dv"><int>30</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str><str>red green yellow</str></arr><arr name="lowerfilt1"><str>red green yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.553Z</date><long name="_version_">1641115340560662528</long><int name="intDvoDefault">42</int></doc><doc><str name="id">31</str><arr name="range_facet_l"><long>31</long></arr><int name="id_i1">31</int><arr name="range_facet_l_dv"><long>31</long></arr><arr name="range_facet_i_dv"><int>31</int></arr><arr name="lowerfilt"><str>The fat red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The fat red fox jumped over the lazy brown dogs.</str><str>green blue yellow</str></arr><arr name="lowerfilt1"><str>green blue yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.553Z</date><long name="_version_">1641115340560662529</long><int name="intDvoDefault">42</int></doc></result>
</response>
request was:q={!mlt+qf%3Dlowerfilt}17
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
... 40 more
Build Log:
[...truncated 15892 lines...]
[junit4] Suite: org.apache.solr.search.mlt.SimpleMLTQParserTest
[junit4] 2> 2585384 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.SimpleMLTQParserTest_A9C4229C0DE00686-001/init-core-data-001
[junit4] 2> 2585385 WARN (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=17 numCloses=17
[junit4] 2> 2585385 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2585396 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2585396 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 2585396 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2585411 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1
[junit4] 2> 2585442 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 2585545 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2585677 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2585678 WARN (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@33dc1f09[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2585678 WARN (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@33dc1f09[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2585681 WARN (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3151ba34[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2585681 WARN (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3151ba34[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2585693 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2585693 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2585709 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62e81048
[junit4] 2> 2585718 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62e81048
[junit4] 2> 2585719 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@62e81048
[junit4] 2> 2585720 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2585737 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.1
[junit4] 2> 2585765 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2585848 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2585853 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2585854 INFO (coreLoadExecutor-13414-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@62e81048
[junit4] 2> 2585854 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.SimpleMLTQParserTest_A9C4229C0DE00686-001/init-core-data-001/]
[junit4] 2> 2585855 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=41.9619140625, floorSegmentMB=1.564453125, forceMergeDeletesPctAllowed=21.394016995093562, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=37.11717318582622
[junit4] 2> 2585887 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2585887 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2585888 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2585888 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2585888 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@25a3b1c5
[junit4] 2> 2585889 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4be96326[collection1] main]
[junit4] 2> 2585889 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2585977 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2586070 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2586070 INFO (coreLoadExecutor-13414-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1641115340536545280
[junit4] 2> 2586071 INFO (searcherExecutor-13415-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2586072 INFO (searcherExecutor-13415-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2586072 INFO (searcherExecutor-13415-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2586072 INFO (searcherExecutor-13415-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2586072 INFO (searcherExecutor-13415-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2586072 INFO (searcherExecutor-13415-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2586072 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 2586073 INFO (searcherExecutor-13415-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2586073 INFO (searcherExecutor-13415-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2586073 INFO (searcherExecutor-13415-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2586074 INFO (searcherExecutor-13415-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4be96326[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2586076 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTest
[junit4] 2> 2586078 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1 (1641115340543885312)]} 0 1
[junit4] 2> 2586080 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2 (1641115340545982464)]} 0 0
[junit4] 2> 2586080 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3 (1641115340547031040)]} 0 0
[junit4] 2> 2586080 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4 (1641115340547031041)]} 0 0
[junit4] 2> 2586080 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5 (1641115340547031042)]} 0 0
[junit4] 2> 2586080 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6 (1641115340547031043)]} 0 0
[junit4] 2> 2586081 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7 (1641115340548079616)]} 0 0
[junit4] 2> 2586081 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8 (1641115340548079617)]} 0 0
[junit4] 2> 2586081 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9 (1641115340548079618)]} 0 0
[junit4] 2> 2586081 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10 (1641115340548079619)]} 0 0
[junit4] 2> 2586081 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[12 (1641115340548079620)]} 0 0
[junit4] 2> 2586082 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[13 (1641115340549128192)]} 0 0
[junit4] 2> 2586082 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[14 (1641115340549128193)]} 0 0
[junit4] 2> 2586087 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15 (1641115340549128194)]} 0 4
[junit4] 2> 2586087 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[16 (1641115340554371072)]} 0 0
[junit4] 2> 2586088 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[17 (1641115340555419648)]} 0 0
[junit4] 2> 2586088 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[18 (1641115340555419649)]} 0 0
[junit4] 2> 2586088 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[19 (1641115340555419650)]} 0 0
[junit4] 2> 2586088 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[20 (1641115340555419651)]} 0 0
[junit4] 2> 2586089 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[21 (1641115340555419652)]} 0 0
[junit4] 2> 2586089 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[22 (1641115340556468224)]} 0 0
[junit4] 2> 2586089 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[23 (1641115340556468225)]} 0 0
[junit4] 2> 2586089 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[24 (1641115340556468226)]} 0 0
[junit4] 2> 2586089 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[25 (1641115340556468227)]} 0 0
[junit4] 2> 2586089 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[26 (1641115340556468228)]} 0 0
[junit4] 2> 2586090 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[27 (1641115340557516800)]} 0 0
[junit4] 2> 2586090 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[28 (1641115340557516801)]} 0 0
[junit4] 2> 2586092 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[29 (1641115340557516802)]} 0 2
[junit4] 2> 2586093 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[30 (1641115340560662528)]} 0 0
[junit4] 2> 2586093 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[31 (1641115340560662529)]} 0 0
[junit4] 2> 2586093 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[32 (1641115340560662530)]} 0 0
[junit4] 2> 2586094 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1641115340561711104,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2586094 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@686efbe9 commitCommandVersion:1641115340561711104
[junit4] 2> 2586100 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e4a5012[collection1] main]
[junit4] 2> 2586101 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2586102 INFO (searcherExecutor-13415-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e4a5012[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.2.1):c31:[diagnostics={os=Linux, java.version=12.0.1, os.arch=amd64, java.runtime.version=12.0.1+12, source=merge, os.version=5.0.0-23-generic, java.vendor=AdoptOpenJDK, java.vm.version=12.0.1+12, lucene.version=8.2.1, mergeMaxNumSegments=-1, mergeFactor=3, timestamp=1565089550556}])))}
[junit4] 2> 2586103 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 8
[junit4] 2> 2586104 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!mlt+qf%3Dlowerfilt}17} hits=16 status=0 QTime=1
[junit4] 2> 2586105 ERROR (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result/doc[1]/str[@name='id'][.='13']
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="16" start="0"><doc><str name="id">16</str><arr name="range_facet_l"><long>16</long></arr><int name="id_i1">16</int><arr name="range_facet_l_dv"><long>16</long></arr><arr name="range_facet_i_dv"><int>16</int></arr><arr name="lowerfilt"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.547Z</date><long name="_version_">1641115340554371072</long><int name="intDvoDefault">42</int></doc><doc><str name="id">18</str><arr name="range_facet_l"><long>18</long></arr><int name="id_i1">18</int><arr name="range_facet_l_dv"><long>18</long></arr><arr name="range_facet_i_dv"><int>18</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419649</long><int name="intDvoDefault">42</int></doc><doc><str name="id">19</str><arr name="range_facet_l"><long>19</long></arr><int name="id_i1">19</int><arr name="range_facet_l_dv"><long>19</long></arr><arr name="range_facet_i_dv"><int>19</int></arr><arr name="lowerfilt"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419650</long><int name="intDvoDefault">42</int></doc><doc><str name="id">20</str><arr name="range_facet_l"><long>20</long></arr><int name="id_i1">20</int><arr name="range_facet_l_dv"><long>20</long></arr><arr name="range_facet_i_dv"><int>20</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419651</long><int name="intDvoDefault">42</int></doc><doc><str name="id">21</str><arr name="range_facet_l"><long>21</long></arr><int name="id_i1">21</int><arr name="range_facet_l_dv"><long>21</long></arr><arr name="range_facet_i_dv"><int>21</int></arr><arr name="lowerfilt"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419652</long><int name="intDvoDefault">42</int></doc><doc><str name="id">22</str><arr name="range_facet_l"><long>22</long></arr><int name="id_i1">22</int><arr name="range_facet_l_dv"><long>22</long></arr><arr name="range_facet_i_dv"><int>22</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468224</long><int name="intDvoDefault">42</int></doc><doc><str name="id">23</str><arr name="range_facet_l"><long>23</long></arr><int name="id_i1">23</int><arr name="range_facet_l_dv"><long>23</long></arr><arr name="range_facet_i_dv"><int>23</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468225</long><int name="intDvoDefault">42</int></doc><doc><str name="id">24</str><arr name="range_facet_l"><long>24</long></arr><int name="id_i1">24</int><arr name="range_facet_l_dv"><long>24</long></arr><arr name="range_facet_i_dv"><int>24</int></arr><arr name="lowerfilt"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468226</long><int name="intDvoDefault">42</int></doc><doc><str name="id">30</str><arr name="range_facet_l"><long>30</long></arr><int name="id_i1">30</int><arr name="range_facet_l_dv"><long>30</long></arr><arr name="range_facet_i_dv"><int>30</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str><str>red green yellow</str></arr><arr name="lowerfilt1"><str>red green yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.553Z</date><long name="_version_">1641115340560662528</long><int name="intDvoDefault">42</int></doc><doc><str name="id">31</str><arr name="range_facet_l"><long>31</long></arr><int name="id_i1">31</int><arr name="range_facet_l_dv"><long>31</long></arr><arr name="range_facet_i_dv"><int>31</int></arr><arr name="lowerfilt"><str>The fat red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The fat red fox jumped over the lazy brown dogs.</str><str>green blue yellow</str></arr><arr name="lowerfilt1"><str>green blue yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.553Z</date><long name="_version_">1641115340560662529</long><int name="intDvoDefault">42</int></doc></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q={!mlt+qf%3Dlowerfilt}17
[junit4] 2> 2586106 ERROR (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q={!mlt+qf%3Dlowerfilt}17:java.lang.RuntimeException: REQUEST FAILED: xpath=//result/doc[1]/str[@name='id'][.='13']
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="16" start="0"><doc><str name="id">16</str><arr name="range_facet_l"><long>16</long></arr><int name="id_i1">16</int><arr name="range_facet_l_dv"><long>16</long></arr><arr name="range_facet_i_dv"><int>16</int></arr><arr name="lowerfilt"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.547Z</date><long name="_version_">1641115340554371072</long><int name="intDvoDefault">42</int></doc><doc><str name="id">18</str><arr name="range_facet_l"><long>18</long></arr><int name="id_i1">18</int><arr name="range_facet_l_dv"><long>18</long></arr><arr name="range_facet_i_dv"><int>18</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419649</long><int name="intDvoDefault">42</int></doc><doc><str name="id">19</str><arr name="range_facet_l"><long>19</long></arr><int name="id_i1">19</int><arr name="range_facet_l_dv"><long>19</long></arr><arr name="range_facet_i_dv"><int>19</int></arr><arr name="lowerfilt"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419650</long><int name="intDvoDefault">42</int></doc><doc><str name="id">20</str><arr name="range_facet_l"><long>20</long></arr><int name="id_i1">20</int><arr name="range_facet_l_dv"><long>20</long></arr><arr name="range_facet_i_dv"><int>20</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419651</long><int name="intDvoDefault">42</int></doc><doc><str name="id">21</str><arr name="range_facet_l"><long>21</long></arr><int name="id_i1">21</int><arr name="range_facet_l_dv"><long>21</long></arr><arr name="range_facet_i_dv"><int>21</int></arr><arr name="lowerfilt"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419652</long><int name="intDvoDefault">42</int></doc><doc><str name="id">22</str><arr name="range_facet_l"><long>22</long></arr><int name="id_i1">22</int><arr name="range_facet_l_dv"><long>22</long></arr><arr name="range_facet_i_dv"><int>22</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468224</long><int name="intDvoDefault">42</int></doc><doc><str name="id">23</str><arr name="range_facet_l"><long>23</long></arr><int name="id_i1">23</int><arr name="range_facet_l_dv"><long>23</long></arr><arr name="range_facet_i_dv"><int>23</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468225</long><int name="intDvoDefault">42</int></doc><doc><str name="id">24</str><arr name="range_facet_l"><long>24</long></arr><int name="id_i1">24</int><arr name="range_facet_l_dv"><long>24</long></arr><arr name="range_facet_i_dv"><int>24</int></arr><arr name="lowerfilt"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468226</long><int name="intDvoDefault">42</int></doc><doc><str name="id">30</str><arr name="range_facet_l"><long>30</long></arr><int name="id_i1">30</int><arr name="range_facet_l_dv"><long>30</long></arr><arr name="range_facet_i_dv"><int>30</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str><str>red green yellow</str></arr><arr name="lowerfilt1"><str>red green yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.553Z</date><long name="_version_">1641115340560662528</long><int name="intDvoDefault">42</int></doc><doc><str name="id">31</str><arr name="range_facet_l"><long>31</long></arr><int name="id_i1">31</int><arr name="range_facet_l_dv"><long>31</long></arr><arr name="range_facet_i_dv"><int>31</int></arr><arr name="lowerfilt"><str>The fat red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The fat red fox jumped over the lazy brown dogs.</str><str>green blue yellow</str></arr><arr name="lowerfilt1"><str>green blue yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.553Z</date><long name="_version_">1641115340560662529</long><int name="intDvoDefault">42</int></doc></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q={!mlt+qf%3Dlowerfilt}17
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:907)
[junit4] 2> at org.apache.solr.search.mlt.SimpleMLTQParserTest.doTest(SimpleMLTQParserTest.java:82)
[junit4] 2> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.ev...
[junit4] 2> 2586106 INFO (TEST-SimpleMLTQParserTest.doTest-seed#[A9C4229C0DE00686]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SimpleMLTQParserTest -Dtests.method=doTest -Dtests.seed=A9C4229C0DE00686 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=pt-TL -Dtests.timezone=Pacific/Chatham -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.04s J1 | SimpleMLTQParserTest.doTest <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([A9C4229C0DE00686:E809A38605B153F]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:947)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:907)
[junit4] > at org.apache.solr.search.mlt.SimpleMLTQParserTest.doTest(SimpleMLTQParserTest.java:82)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result/doc[1]/str[@name='id'][.='13']
[junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><result name="response" numFound="16" start="0"><doc><str name="id">16</str><arr name="range_facet_l"><long>16</long></arr><int name="id_i1">16</int><arr name="range_facet_l_dv"><long>16</long></arr><arr name="range_facet_i_dv"><int>16</int></arr><arr name="lowerfilt"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The slim red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.547Z</date><long name="_version_">1641115340554371072</long><int name="intDvoDefault">42</int></doc><doc><str name="id">18</str><arr name="range_facet_l"><long>18</long></arr><int name="id_i1">18</int><arr name="range_facet_l_dv"><long>18</long></arr><arr name="range_facet_i_dv"><int>18</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419649</long><int name="intDvoDefault">42</int></doc><doc><str name="id">19</str><arr name="range_facet_l"><long>19</long></arr><int name="id_i1">19</int><arr name="range_facet_l_dv"><long>19</long></arr><arr name="range_facet_i_dv"><int>19</int></arr><arr name="lowerfilt"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The hose red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419650</long><int name="intDvoDefault">42</int></doc><doc><str name="id">20</str><arr name="range_facet_l"><long>20</long></arr><int name="id_i1">20</int><arr name="range_facet_l_dv"><long>20</long></arr><arr name="range_facet_i_dv"><int>20</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419651</long><int name="intDvoDefault">42</int></doc><doc><str name="id">21</str><arr name="range_facet_l"><long>21</long></arr><int name="id_i1">21</int><arr name="range_facet_l_dv"><long>21</long></arr><arr name="range_facet_i_dv"><int>21</int></arr><arr name="lowerfilt"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.548Z</date><long name="_version_">1641115340555419652</long><int name="intDvoDefault">42</int></doc><doc><str name="id">22</str><arr name="range_facet_l"><long>22</long></arr><int name="id_i1">22</int><arr name="range_facet_l_dv"><long>22</long></arr><arr name="range_facet_i_dv"><int>22</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468224</long><int name="intDvoDefault">42</int></doc><doc><str name="id">23</str><arr name="range_facet_l"><long>23</long></arr><int name="id_i1">23</int><arr name="range_facet_l_dv"><long>23</long></arr><arr name="range_facet_i_dv"><int>23</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468225</long><int name="intDvoDefault">42</int></doc><doc><str name="id">24</str><arr name="range_facet_l"><long>24</long></arr><int name="id_i1">24</int><arr name="range_facet_l_dv"><long>24</long></arr><arr name="range_facet_i_dv"><int>24</int></arr><arr name="lowerfilt"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.549Z</date><long name="_version_">1641115340556468226</long><int name="intDvoDefault">42</int></doc><doc><str name="id">30</str><arr name="range_facet_l"><long>30</long></arr><int name="id_i1">30</int><arr name="range_facet_l_dv"><long>30</long></arr><arr name="range_facet_i_dv"><int>30</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str><str>red green yellow</str></arr><arr name="lowerfilt1"><str>red green yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.553Z</date><long name="_version_">1641115340560662528</long><int name="intDvoDefault">42</int></doc><doc><str name="id">31</str><arr name="range_facet_l"><long>31</long></arr><int name="id_i1">31</int><arr name="range_facet_l_dv"><long>31</long></arr><arr name="range_facet_i_dv"><int>31</int></arr><arr name="lowerfilt"><str>The fat red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The fat red fox jumped over the lazy brown dogs.</str><str>green blue yellow</str></arr><arr name="lowerfilt1"><str>green blue yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:05:50.553Z</date><long name="_version_">1641115340560662529</long><int name="intDvoDefault">42</int></doc></result>
[junit4] > </response>
[junit4] > request was:q={!mlt+qf%3Dlowerfilt}17
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
[junit4] > ... 40 more
[junit4] 2> 2586108 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2586108 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2082507186
[junit4] 2> 2586108 INFO (coreCloseExecutor-13420-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3d010dc4
[junit4] 2> 2586108 INFO (coreCloseExecutor-13420-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=3d010dc4
[junit4] 2> 2586108 INFO (coreCloseExecutor-13420-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@61d2a4f9: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.M
[...truncated too long message...]
werfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.767Z</date><long name="_version_">1641116389361057792</long><int name="intDvoDefault">42</int></doc><doc><str name="id">21</str><arr name="range_facet_l"><long>21</long></arr><int name="id_i1">21</int><arr name="range_facet_l_dv"><long>21</long></arr><arr name="range_facet_i_dv"><int>21</int></arr><arr name="lowerfilt"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The court red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.770Z</date><long name="_version_">1641116389364203520</long><int name="intDvoDefault">42</int></doc><doc><str name="id">22</str><arr name="range_facet_l"><long>22</long></arr><int name="id_i1">22</int><arr name="range_facet_l_dv"><long>22</long></arr><arr name="range_facet_i_dv"><int>22</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.775Z</date><long name="_version_">1641116389369446400</long><int name="intDvoDefault">42</int></doc><doc><str name="id">23</str><arr name="range_facet_l"><long>23</long></arr><int name="id_i1">23</int><arr name="range_facet_l_dv"><long>23</long></arr><arr name="range_facet_i_dv"><int>23</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.777Z</date><long name="_version_">1641116389371543552</long><int name="intDvoDefault">42</int></doc><doc><str name="id">24</str><arr name="range_facet_l"><long>24</long></arr><int name="id_i1">24</int><arr name="range_facet_l_dv"><long>24</long></arr><arr name="range_facet_i_dv"><int>24</int></arr><arr name="lowerfilt"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The file red fox jumped over the lazy brown dogs.</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.779Z</date><long name="_version_">1641116389373640704</long><int name="intDvoDefault">42</int></doc><doc><str name="id">30</str><arr name="range_facet_l"><long>30</long></arr><int name="id_i1">30</int><arr name="range_facet_l_dv"><long>30</long></arr><arr name="range_facet_i_dv"><int>30</int></arr><arr name="lowerfilt"><str>The quote red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The quote red fox jumped over the lazy brown dogs.</str><str>red green yellow</str></arr><arr name="lowerfilt1"><str>red green yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.797Z</date><long name="_version_">1641116389393563648</long><int name="intDvoDefault">42</int></doc><doc><str name="id">31</str><arr name="range_facet_l"><long>31</long></arr><int name="id_i1">31</int><arr name="range_facet_l_dv"><long>31</long></arr><arr name="range_facet_i_dv"><int>31</int></arr><arr name="lowerfilt"><str>The fat red fox jumped over the lazy brown dogs.</str></arr><arr name="lowerfilt1and2"><str>The fat red fox jumped over the lazy brown dogs.</str><str>green blue yellow</str></arr><arr name="lowerfilt1"><str>green blue yellow</str></arr><arr name="multiDefault"><str>muLti-Default</str></arr><int name="intDefault">42</int><date name="timestamp">2019-08-06T11:22:30.800Z</date><long name="_version_">1641116389395660800</long><int name="intDvoDefault">42</int></doc></result>
[junit4] > </response>
[junit4] > request was:q={!mlt+qf%3Dlowerfilt}17
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
[junit4] > ... 40 more
[junit4] 2> 11534 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 11534 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=369800934
[junit4] 2> 11535 INFO (coreCloseExecutor-31-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@501c2b5e
[junit4] 2> 11535 INFO (coreCloseExecutor-31-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=501c2b5e
[junit4] 2> 11535 INFO (coreCloseExecutor-31-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@58fdb5b4: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2e44c4c1
[junit4] 2> 11566 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 11566 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b75d082: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@6094caee
[junit4] 2> 11573 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 11573 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c0f31bc: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@52af5e0a
[junit4] 2> 11573 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 11574 INFO (SUITE-SimpleMLTQParserTest-seed#[A9C4229C0DE00686]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c7ff2cf: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@564ff312
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.2-Linux/solr/build/solr-core/test/J0/temp/solr.search.mlt.SimpleMLTQParserTest_A9C4229C0DE00686-002
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=19815, maxDocsPerChunk=731, blockSize=775), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=19815, blockSize=775)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@63971063), locale=pt-TL, timezone=Pacific/Chatham
[junit4] 2> NOTE: Linux 5.0.0-23-generic amd64/AdoptOpenJDK 12.0.1 (64-bit)/cpus=8,threads=1,free=352210856,total=509607936
[junit4] 2> NOTE: All tests run in this JVM: [SimpleMLTQParserTest, SimpleMLTQParserTest]
[junit4] Completed [5/5 (5!)] on J0 in 2.56s, 1 test, 1 error <<< FAILURES!
[...truncated 15 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-8.2-Linux/lucene/common-build.xml:1572: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.2-Linux/lucene/common-build.xml:1099: There were test failures: 5 suites, 5 tests, 5 errors [seed: A9C4229C0DE00686]
Total time: 14 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 5/5 failed: org.apache.solr.search.mlt.SimpleMLTQParserTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=7b0c7cada62a361aa9b00ecedb804e890f1724d4, workspace=/home/jenkins/workspace/Lucene-Solr-8.2-Linux
[WARNINGS] Computing warning deltas based on reference build #525
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2