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 2018/07/25 18:51:07 UTC
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-10.0.1) - Build #
22525 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22525/
Java: 64bit/jdk-10.0.1 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenRenew
Error Message:
expected:<200> but was:<403>
Stack Trace:
java.lang.AssertionError: expected:<200> but was:<403>
at __randomizedtesting.SeedInfo.seed([24085B44C07ED9CF:1393AF5AF8B2046B]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.renewDelegationToken(TestDelegationWithHadoopAuth.java:120)
at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.verifyDelegationTokenRenew(TestDelegationWithHadoopAuth.java:302)
at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenRenew(TestDelegationWithHadoopAuth.java:319)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:844)
Build Log:
[...truncated 15001 lines...]
[junit4] Suite: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_24085B44C07ED9CF-001/init-core-data-001
[junit4] 2> 2104669 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[24085B44C07ED9CF]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_24085B44C07ED9CF-001/tempDir-001
[junit4] 2> 2104669 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[24085B44C07ED9CF]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2104675 INFO (Thread-7050) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2104675 INFO (Thread-7050) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2104702 ERROR (Thread-7050) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 2104773 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[24085B44C07ED9CF]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:43513
[junit4] 2> 2104794 INFO (zkConnectionManagerCallback-8674-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2104812 INFO (jetty-launcher-8671-thread-1) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 10.0.1+10
[junit4] 2> 2104816 INFO (jetty-launcher-8671-thread-2) [ ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 10.0.1+10
[junit4] 2> 2104891 INFO (jetty-launcher-8671-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2104891 INFO (jetty-launcher-8671-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2104891 INFO (jetty-launcher-8671-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2104891 INFO (jetty-launcher-8671-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@503ae6d{/solr,null,AVAILABLE}
[junit4] 2> 2104892 INFO (jetty-launcher-8671-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@75562ecd{SSL,[ssl, http/1.1]}{127.0.0.1:44499}
[junit4] 2> 2104892 INFO (jetty-launcher-8671-thread-1) [ ] o.e.j.s.Server Started @2104932ms
[junit4] 2> 2104892 INFO (jetty-launcher-8671-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44499}
[junit4] 2> 2104892 ERROR (jetty-launcher-8671-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2104892 INFO (jetty-launcher-8671-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2104893 INFO (jetty-launcher-8671-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 2104893 INFO (jetty-launcher-8671-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2104893 INFO (jetty-launcher-8671-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2104893 INFO (jetty-launcher-8671-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-25T18:32:57.829077Z
[junit4] 2> 2104977 INFO (zkConnectionManagerCallback-8676-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2104982 INFO (jetty-launcher-8671-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2105031 INFO (jetty-launcher-8671-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2105031 INFO (jetty-launcher-8671-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2105031 INFO (jetty-launcher-8671-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2105033 INFO (jetty-launcher-8671-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1c7bc0d2{/solr,null,AVAILABLE}
[junit4] 2> 2105034 INFO (jetty-launcher-8671-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@14e3a646{SSL,[ssl, http/1.1]}{127.0.0.1:45193}
[junit4] 2> 2105034 INFO (jetty-launcher-8671-thread-2) [ ] o.e.j.s.Server Started @2105074ms
[junit4] 2> 2105034 INFO (jetty-launcher-8671-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45193}
[junit4] 2> 2105034 ERROR (jetty-launcher-8671-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2105034 INFO (jetty-launcher-8671-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2105034 INFO (jetty-launcher-8671-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 2105034 INFO (jetty-launcher-8671-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2105034 INFO (jetty-launcher-8671-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2105034 INFO (jetty-launcher-8671-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-07-25T18:32:57.970726Z
[junit4] 2> 2105063 INFO (zkConnectionManagerCallback-8678-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2105075 INFO (jetty-launcher-8671-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2105211 INFO (jetty-launcher-8671-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43513/solr
[junit4] 2> 2105262 INFO (zkConnectionManagerCallback-8682-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2105325 INFO (zkConnectionManagerCallback-8684-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2105347 INFO (jetty-launcher-8671-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43513/solr
[junit4] 2> 2105350 INFO (zkConnectionManagerCallback-8690-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2105366 INFO (zkConnectionManagerCallback-8692-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2105372 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45193_solr
[junit4] 2> 2105373 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.c.Overseer Overseer (id=72189501914218502-127.0.0.1:45193_solr-n_0000000000) starting
[junit4] 2> 2105377 INFO (zkConnectionManagerCallback-8699-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2105378 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43513/solr ready
[junit4] 2> 2105379 INFO (OverseerStateUpdate-72189501914218502-127.0.0.1:45193_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:45193_solr
[junit4] 2> 2105379 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45193_solr
[junit4] 2> 2105380 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 2105380 INFO (zkCallback-8691-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2105380 INFO (zkCallback-8698-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2105385 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.s.HadoopAuthPlugin Params: {delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, type=simple, zk-dt-secret-manager.enable=true, signer.secret.provider=zookeeper}
[junit4] 2> 2105422 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2105425 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2105426 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44499_solr
[junit4] 2> 2105426 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HadoopAuthPlugin
[junit4] 2> 2105435 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.s.HadoopAuthPlugin Params: {delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, signer.secret.provider.zookeeper.path=/token, type=simple, zk-dt-secret-manager.enable=true, signer.secret.provider=zookeeper}
[junit4] 2> 2105446 INFO (zkCallback-8698-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2105453 INFO (zkCallback-8691-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2105454 INFO (zkCallback-8683-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2105471 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 2105472 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 2105504 INFO (jetty-launcher-8671-thread-2-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 2105506 INFO (jetty-launcher-8671-thread-1-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 2106563 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests
[junit4] 2> 2106567 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.c.CoreContainer PKIAuthenticationPlugin is managing internode requests
[junit4] 2> 2106575 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2106587 INFO (zkConnectionManagerCallback-8705-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2106588 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45193.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e1d1f9e
[junit4] 2> 2106594 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2106595 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43513/solr ready
[junit4] 2> 2106595 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45193.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e1d1f9e
[junit4] 2> 2106596 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45193.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e1d1f9e
[junit4] 2> 2106596 INFO (jetty-launcher-8671-thread-2) [n:127.0.0.1:45193_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_24085B44C07ED9CF-001/tempDir-001/node2/.
[junit4] 2> 2106601 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2106611 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44499.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e1d1f9e
[junit4] 2> 2106617 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44499.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e1d1f9e
[junit4] 2> 2106617 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44499.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2e1d1f9e
[junit4] 2> 2106618 INFO (jetty-launcher-8671-thread-1) [n:127.0.0.1:44499_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_24085B44C07ED9CF-001/tempDir-001/node1/.
[junit4] 2> 2106645 INFO (zkConnectionManagerCallback-8708-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2106699 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenSolrClient-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 2106714 INFO (qtp847752503-28001) [n:127.0.0.1:45193_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAWTStzXgigFk9sO54AEEFI4FS8HIERrM4_mCaU_z9J7LTzzyB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2106714 INFO (qtp847752503-28001) [n:127.0.0.1:45193_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAWTStzXgigFk9sO54AEEFI4FS8HIERrM4_mCaU_z9J7LTzzyB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2106751 INFO (qtp847752503-27910) [n:127.0.0.1:45193_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params op=someSolrOperation&action=LIST&user.name=foo&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2106751 INFO (qtp847752503-27910) [n:127.0.0.1:45193_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={op=someSolrOperation&action=LIST&user.name=foo&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2106752 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationOtherOp-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=foo&p=foo&t=simple-dt&e=1532579579686&s=EA46VAp996zi3rMnivmnUvyKNbo="; Domain=127.0.0.1; Expires= am, 26-Lul-2018 04:32:59 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 26-Lul-2018 04:32:59 GMT
[junit4] 2> 2106771 INFO (zkConnectionManagerCallback-8711-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2106809 ERROR (qtp847752503-27923) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_3]
[junit4] 2> 2106819 INFO (zkConnectionManagerCallback-8716-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2106820 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2106821 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43513/solr ready
[junit4] 2> 2106847 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 2106853 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 2106883 INFO (zkConnectionManagerCallback-8721-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2106893 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2106893 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43513/solr ready
[junit4] 2> 2106896 ERROR (qtp21429444-27913) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_4]
[junit4] 2> 2106911 INFO (zkConnectionManagerCallback-8726-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2106911 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2106913 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43513/solr ready
[junit4] 2> 2106915 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 2106967 INFO (zkConnectionManagerCallback-8731-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2106967 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2106968 INFO (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43513/solr ready
[junit4] 2> 2106971 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenCancel-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 2106986 WARN (qtp847752503-27923) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: foo is trying to renew a token with wrong password
[junit4] 2> 2106986 WARN (qtp847752503-27923) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 2106987 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenew-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDelegationWithHadoopAuth -Dtests.method=testDelegationTokenRenew -Dtests.seed=24085B44C07ED9CF -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mt-MT -Dtests.timezone=Europe/Ljubljana -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.02s J0 | TestDelegationWithHadoopAuth.testDelegationTokenRenew <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<200> but was:<403>
[junit4] > at __randomizedtesting.SeedInfo.seed([24085B44C07ED9CF:1393AF5AF8B2046B]:0)
[junit4] > at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.renewDelegationToken(TestDelegationWithHadoopAuth.java:120)
[junit4] > at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.verifyDelegationTokenRenew(TestDelegationWithHadoopAuth.java:302)
[junit4] > at org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth.testDelegationTokenRenew(TestDelegationWithHadoopAuth.java:319)
[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:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 2107015 INFO (qtp847752503-27922) [n:127.0.0.1:45193_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAWTStzcpigFk9sO7KQYEFDkF_WK-DYVvV9xWW72KXUKOtKTOB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2107015 INFO (qtp847752503-27922) [n:127.0.0.1:45193_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAWTStzcpigFk9sO7KQYEFDkF_WK-DYVvV9xWW72KXUKOtKTOB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2107019 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 2107024 INFO (qtp21429444-27911) [n:127.0.0.1:44499_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADZm9vA2ZvbwCKAWTStzcpigFk9sO7KQYEFDkF_WK-DYVvV9xWW72KXUKOtKTOB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2107024 INFO (qtp21429444-27911) [n:127.0.0.1:44499_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADZm9vA2ZvbwCKAWTStzcpigFk9sO7KQYEFDkF_WK-DYVvV9xWW72KXUKOtKTOB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2107027 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenVerify-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 2107067 ERROR (qtp21429444-27911) [n:127.0.0.1:44499_solr ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_7]
[junit4] 2> 2107068 ERROR (qtp847752503-28001) [n:127.0.0.1:45193_solr ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_7]
[junit4] 2> 2107075 ERROR (qtp21429444-27911) [n:127.0.0.1:44499_solr ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_8]
[junit4] 2> 2107076 ERROR (qtp847752503-28001) [n:127.0.0.1:45193_solr ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_8]
[junit4] 2> 2107105 WARN (qtp847752503-28001) [n:127.0.0.1:45193_solr ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: bar tries to renew a token with renewer foo
[junit4] 2> 2107105 WARN (qtp847752503-28001) [n:127.0.0.1:45193_solr ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 2107106 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 2107107 WARN (qtp21429444-28013) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: bar tries to renew a token with renewer foo
[junit4] 2> 2107107 WARN (qtp21429444-28013) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 2107108 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 2107115 WARN (qtp847752503-27923) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew a token with renewer renewUser
[junit4] 2> 2107115 WARN (qtp847752503-27923) [ ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 2107115 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 2107116 WARN (qtp21429444-27911) [n:127.0.0.1:44499_solr ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew a token with renewer renewUser
[junit4] 2> 2107116 WARN (qtp21429444-27911) [n:127.0.0.1:44499_solr ] o.a.s.s.HadoopAuthPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 2107116 WARN (TEST-TestDelegationWithHadoopAuth.testDelegationTokenRenewFail-seed#[24085B44C07ED9CF]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Domain=127.0.0.1; Expires= am, 01-Jan-1970 00:00:00 GMT; Secure; HttpOnly". Invalid 'expires' attribute: am, 01-Jan-1970 00:00:00 GMT
[junit4] 2> 2107119 INFO (jetty-closer-8672-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@14e3a646{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 2107121 INFO (jetty-closer-8672-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1204854379
[junit4] 2> 2107121 INFO (jetty-closer-8672-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2107122 INFO (jetty-closer-8672-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1cf9e488: rootName = solr_45193, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@110734af
[junit4] 2> 2107127 INFO (jetty-closer-8672-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@75562ecd{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 2107128 INFO (jetty-closer-8672-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1702319666
[junit4] 2> 2107128 INFO (jetty-closer-8672-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2107128 INFO (jetty-closer-8672-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@24a4b682: rootName = solr_44499, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@511b1fbe
[junit4] 2> 2107128 INFO (jetty-closer-8672-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2107128 INFO (jetty-closer-8672-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5353acaf: rootName = solr_45193, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4fee8969
[junit4] 2> 2107132 INFO (jetty-closer-8672-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2107132 INFO (jetty-closer-8672-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3eb23657: rootName = solr_45193, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@27d17a08
[junit4] 2> 2107133 INFO (jetty-closer-8672-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:45193_solr
[junit4] 2> 2107134 INFO (jetty-closer-8672-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2107134 INFO (jetty-closer-8672-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@27ddf9d4: rootName = solr_44499, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4fee8969
[junit4] 2> 2107134 INFO (zkCallback-8683-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2107134 INFO (zkCallback-8691-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2107135 INFO (zkCallback-8698-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2107135 INFO (jetty-closer-8672-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2107136 INFO (jetty-closer-8672-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2107136 INFO (jetty-closer-8672-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72189501914218502-127.0.0.1:45193_solr-n_0000000000) closing
[junit4] 2> 2107136 INFO (zkCallback-8704-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2107136 INFO (OverseerStateUpdate-72189501914218502-127.0.0.1:45193_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:45193_solr
[junit4] 2> 2107136 WARN (OverseerAutoScalingTriggerThread-72189501914218502-127.0.0.1:45193_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2107137 INFO (jetty-closer-8672-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2107137 INFO (jetty-closer-8672-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@d1c8fc9: rootName = solr_44499, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@27d17a08
[junit4] 2> 2107157 INFO (jetty-closer-8672-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44499_solr
[junit4] 2> 2107158 INFO (jetty-closer-8672-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2107158 INFO (zkCallback-8683-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44499_solr
[junit4] 2> 2107158 ERROR (Thread[Thread-7067,5,TGRP-TestDelegationWithHadoopAuth]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 2107158 INFO (zkCallback-8704-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 2107159 INFO (zkCallback-8683-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 2107162 INFO (jetty-closer-8672-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1c7bc0d2{/solr,null,UNAVAILABLE}
[junit4] 2> 2107162 INFO (jetty-closer-8672-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2108659 ERROR (Thread[Thread-7069,5,TGRP-TestDelegationWithHadoopAuth]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 2108660 INFO (jetty-closer-8672-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@503ae6d{/solr,null,UNAVAILABLE}
[junit4] 2> 2108660 INFO (jetty-closer-8672-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2108663 ERROR (SUITE-TestDelegationWithHadoopAuth-seed#[24085B44C07ED9CF]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 2108663 INFO (SUITE-TestDelegationWithHadoopAuth-seed#[24085B44C07ED9CF]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43513 43513
[junit4] 2> 2109465 INFO (Thread-7050) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43513 43513
[junit4] 2> 2109467 WARN (Thread-7050) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/clusterprops.json
[junit4] 2> 8 /solr/clusterstate.json
[junit4] 2> 4 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_7
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_6
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_9
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_8
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_1
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_3
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_2
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_2
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_5
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_4
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_10
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 8 /solr/live_nodes
[junit4] 2> 8 /solr/collections
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.security.hadoop.TestDelegationWithHadoopAuth_24085B44C07ED9CF-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=295, maxMBSortInHeap=6.824470103200098, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1c768101), locale=mt-MT, timezone=Europe/Ljubljana
[junit4] 2> NOTE: Linux 4.15.0-24-generic amd64/Oracle Corporation 10.0.1 (64-bit)/cpus=8,threads=1,free=296725072,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestGenericDistributedQueue, ZkStateReaderTest, HttpTriggerListenerTest, TestSolrQueryParser, HighlighterTest, TestFilteredDocIdSet, HdfsRecoveryZkTest, SuggesterTest, MetricTriggerTest, TestSizeLimitedDistributedMap, TestHalfAndHalfDocValues, ZkControllerTest, DeleteLastCustomShardedReplicaTest, TestBadConfig, DistributedFacetPivotSmallTest, TestInitQParser, TestTriggerIntegration, MigrateRouteKeyTest, TestRuleBasedAuthorizationPlugin, MaxSizeAutoCommitTest, TestPHPSerializedResponseWriter, HdfsBasicDistributedZk2Test, XmlUpdateRequestHandlerTest, TestLRUStatsCache, CollectionsAPIDistributedZkTest, TestFreeTextSuggestions, ZkShardTermsTest, TestSlowCompositeReaderWrapper, SoftAutoCommitTest, RestoreTriggerStateTest, TestPayloadCheckQParserPlugin, FacetPivotSmallTest, TestMiniSolrCloudClusterSSL, TestLocalFSCloudBackupRestore, OverriddenZkACLAndCredentialsProvidersTest, ReturnFieldsTest, LoggingHandlerTest, TestTrieFacet, TestSQLHandlerNonCloud, TestDistribIDF, TestDynamicFieldCollectionResource, TestFieldResource, ShufflingReplicaListTransformerTest, ConjunctionSolrSpellCheckerTest, TestSolrDeletionPolicy1, SolrSlf4jReporterTest, BasicDistributedZk2Test, SpatialHeatmapFacetsTest, DistributedSpellCheckComponentTest, CloudExitableDirectoryReaderTest, DistributedQueryComponentCustomSortTest, TestUnInvertedFieldException, MultiThreadedOCPTest, CheckHdfsIndexTest, TestSSLRandomization, EchoParamsTest, SolrRrdBackendFactoryTest, TermVectorComponentTest, TestNamedUpdateProcessors, TokenizerChainTest, TestEmbeddedSolrServerConstructors, TestGroupingSearch, FullSolrCloudDistribCmdsTest, PreAnalyzedFieldManagedSchemaCloudTest, TestSolrCoreParser, TestRecoveryHdfs, TestSchemaManager, TestNumericTokenStream, SubstringBytesRefFilterTest, RecoveryZkTest, SolrIndexMetricsTest, DataDrivenBlockJoinTest, TestManagedSchemaThreadSafety, TestReqParamsAPI, WordBreakSolrSpellCheckerTest, EmbeddedSolrNoSerializeTest, RegexBoostProcessorTest, TestFieldCacheVsDocValues, ManagedSchemaRoundRobinCloudTest, TestStressInPlaceUpdates, SolrMetricReporterTest, TestMacros, DeleteShardTest, TestExtendedDismaxParser, DocumentAnalysisRequestHandlerTest, MoreLikeThisHandlerTest, ClassificationUpdateProcessorFactoryTest, MetricsConfigTest, ConnectionReuseTest, TestFunctionQuery, TestSolrXml, CdcrBootstrapTest, TestCloudJSONFacetJoinDomain, CloneFieldUpdateProcessorFactoryTest, TestCollectionAPI, AnalysisAfterCoreReloadTest, BitVectorTest, CloudMLTQParserTest, TestSchemaNameResource, LeaderElectionContextKeyTest, TestRestManager, RequestHandlersTest, TestNodeLostTrigger, PrimUtilsTest, TestBinaryField, SecurityConfHandlerTest, TestRandomDVFaceting, OverseerTest, ScheduledTriggerTest, RollingRestartTest, PKIAuthenticationIntegrationTest, BlobRepositoryCloudTest, TestManagedStopFilterFactory, ResponseBuilderTest, ComputePlanActionTest, TestShardHandlerFactory, AddSchemaFieldsUpdateProcessorFactoryTest, TestQuerySenderListener, ExitableDirectoryReaderTest, TestCorePropertiesReload, TestClassicSimilarityFactory, TestPullReplica, AnalyticsMergeStrategyTest, GraphQueryTest, SolrCoreCheckLockOnStartupTest, AliasIntegrationTest, TestCustomSort, TestIBSimilarityFactory, OverseerRolesTest, TestSystemCollAutoCreate, TestSearchPerf, TestMultiWordSynonyms, CursorMarkTest, HLLSerializationTest, LeaderVoteWaitTimeoutTest, CleanupOldIndexTest, CollectionStateFormat2Test, CollectionsAPISolrJTest, LeaderFailoverAfterPartitionTest, LeaderFailureAfterFreshStartTest, LegacyCloudClusterPropTest, MissingSegmentRecoveryTest, MoveReplicaHDFSTest, ReplaceNodeTest, SolrXmlInZkTest, TestCloudConsistency, TestClusterProperties, TestConfigSetsAPIZkFailure, TestDistributedMap, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestLeaderElectionZkExpiry, TestPrepRecovery, CollectionReloadTest, HdfsCollectionsAPIDistributedZkTest, ShardSplitTest, SimpleCollectionCreateDeleteTest, TestHdfsCloudBackupRestore, HdfsAutoAddReplicasIntegrationTest, NodeAddedTriggerIntegrationTest, NodeAddedTriggerTest, SystemLogListenerTest, TriggerSetPropertiesIntegrationTest, CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest, HdfsBasicDistributedZkTest, HdfsChaosMonkeyNothingIsSafeTest, HdfsChaosMonkeySafeLeaderTest, HdfsRestartWhileUpdatingTest, HdfsSyncSliceTest, HdfsThreadLeakTest, AlternateDirectoryTest, ConfigureRecoveryStrategyTest, HdfsDirectoryFactoryTest, PluginInfoTest, TestConfigOverlay, TestConfigSets, TestCustomStream, TestSolrDeletionPolicy2, TestSolrIndexConfig, TestSolrCoreSnapshots, TestCSVLoader, TestCoreBackup, TestHdfsBackupRestoreCore, TestSQLHandler, V2ApiIntegrationTest, V2StandaloneTest, XsltUpdateRequestHandlerTest, AutoscalingHistoryHandlerTest, SuggestComponentContextFilterQueryTest, TermVectorComponentDistributedTest, TestExpandComponent, TestPivotHelperCode, TestExportWriter, JavabinLoaderTest, RandomizedTaggerTest, Tagger2Test, TaggerTest, TaggingAttributeTest, XmlInterpolationTest, FastVectorHighlighterTest, HighlighterConfigTest, HighlighterMaxOffsetTest, TestLegacyFieldReuse, TestMultiValuedNumericRangeQuery, TestNumericRangeQuery64, SolrCloudReportersTest, TestRemoteStreaming, TestStreamBody, TestBinaryResponseWriter, TestJavabinTupleStreamParser, TestPushWriter, TestRawResponseWriter, TestSolrQueryResponse, TestSubQueryTransformerDistrib, TestFieldTypeCollectionResource, TestSchemaSimilarityResource, TestSchemaVersionResource, TestManagedSynonymGraphFilterFactory, BadCopyFieldTest, DateFieldTest, DocValuesMultiTest, EnumFieldTest, ExternalFileFieldSortTest, MultiTermTest, NotRequiredUniqueKeyTest, NumericFieldsTest, OpenExchangeRatesOrgProviderTest, PolyFieldTest, PreAnalyzedFieldTest, PrimitiveFieldTypeTest, RequiredFieldsTest, SpatialRPTFieldTypeTest, TestCloudSchemaless, TestFoldingMultitermQuery, TestGraphTermsQParserPlugin, TestMaxScoreQueryParser, TestNoOpRegenerator, TestOverriddenPrefixQueryForCustomFieldType, TestQueryUtils, TestRTGBase, TestRangeQuery, TestRecovery, TestSha256AuthenticationProvider, TestDelegationWithHadoopAuth]
[junit4] Completed [754/826 (1!)] on J0 in 4.87s, 8 tests, 1 failure <<< FAILURES!
[...truncated 42087 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22525/consoleText
[repro] Revision: 528e8bceac2e1ffcba33b6eb630a52329b34756e
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=TestDelegationWithHadoopAuth -Dtests.method=testDelegationTokenRenew -Dtests.seed=24085B44C07ED9CF -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mt-MT -Dtests.timezone=Europe/Ljubljana -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestDelegationWithHadoopAuth
[repro] ant compile-test
[...truncated 3323 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDelegationWithHadoopAuth" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=24085B44C07ED9CF -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mt-MT -Dtests.timezone=Europe/Ljubljana -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 111 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.security.hadoop.TestDelegationWithHadoopAuth
[repro] Exiting with code 0
[...truncated 40 lines...]
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-10) - Build # 22526 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22526/
Java: 64bit/jdk-10 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.handler.component.InfixSuggestersTest.testShutdownDuringBuild
Error Message:
junit.framework.AssertionFailedError: Unexpected wrapped exception type, expected CoreIsClosedException
Stack Trace:
java.util.concurrent.ExecutionException: junit.framework.AssertionFailedError: Unexpected wrapped exception type, expected CoreIsClosedException
at __randomizedtesting.SeedInfo.seed([216495DF9F0D91A7:FEE9F760A164C4C5]:0)
at java.base/java.util.concurrent.FutureTask.report(FutureTask.java:122)
at java.base/java.util.concurrent.FutureTask.get(FutureTask.java:191)
at org.apache.solr.handler.component.InfixSuggestersTest.testShutdownDuringBuild(InfixSuggestersTest.java:130)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:844)
Caused by: junit.framework.AssertionFailedError: Unexpected wrapped exception type, expected CoreIsClosedException
at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2734)
at org.apache.solr.handler.component.InfixSuggestersTest.lambda$testShutdownDuringBuild$3(InfixSuggestersTest.java:121)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1135)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
... 1 more
Caused by: java.lang.RuntimeException: Exception during query
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:917)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:877)
at org.apache.solr.handler.component.InfixSuggestersTest.lambda$testShutdownDuringBuild$2(InfixSuggestersTest.java:122)
at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2726)
... 6 more
Caused by: org.apache.solr.common.SolrException: SolrCoreState already closed.
at org.apache.solr.update.DefaultSolrCoreState.lock(DefaultSolrCoreState.java:186)
at org.apache.solr.update.DefaultSolrCoreState.getIndexWriter(DefaultSolrCoreState.java:121)
at org.apache.solr.update.SolrCoreState.getTragicException(SolrCoreState.java:198)
at org.apache.solr.core.CoreContainer.checkTragicException(CoreContainer.java:1812)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:212)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2541)
at org.apache.solr.util.TestHarness.query(TestHarness.java:337)
at org.apache.solr.util.TestHarness.query(TestHarness.java:319)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:891)
... 9 more
Build Log:
[...truncated 12662 lines...]
[junit4] Suite: org.apache.solr.handler.component.InfixSuggestersTest
[junit4] 2> 376879 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-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-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.InfixSuggestersTest_216495DF9F0D91A7-001/init-core-data-001
[junit4] 2> 376879 WARN (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 376879 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 376880 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 376881 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 376881 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 376894 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 376913 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 377029 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 377141 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 377141 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 377153 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@499864a6
[junit4] 2> 377158 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@499864a6
[junit4] 2> 377158 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@499864a6
[junit4] 2> 377175 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 377209 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 377218 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 377325 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 377332 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 377332 INFO (coreLoadExecutor-1816-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@499864a6
[junit4] 2> 377334 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 377335 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.InfixSuggestersTest_216495DF9F0D91A7-001/init-core-data-001/]
[junit4] 2> 377336 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 377693 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 377693 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 377694 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=84.376953125, floorSegmentMB=1.775390625, forceMergeDeletesPctAllowed=10.18071962151291, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=47.68128121604244
[junit4] 2> 377694 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@409d2be4[collection1] main]
[junit4] 2> 377694 WARN (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 377695 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 377697 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 377708 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 377708 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 377708 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 377709 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 377709 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 377709 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 377716 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 377716 INFO (coreLoadExecutor-1816-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 377716 INFO (searcherExecutor-1817-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@409d2be4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 377716 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 377718 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test2xBuildReload
[junit4] 2> 377718 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.h.c.SuggestComponent SuggestComponent prepare with : qt=/suggest_analyzing_infix_short_dictionary&suggest=true&suggest.buildAll=true&wt=xml&indent=off
[junit4] 2> 377718 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.s.s.SolrSuggester SolrSuggester.build(shortRandomAnalyzingInfixSuggester)
[junit4] 2> 377758 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.s.s.RandomTestDictionaryFactory org.apache.solr.spelling.suggest.RandomTestDictionaryFactory$RandomTestDictionary.enabled.shortRandomAnalyzingInfixSuggester disabled after emitting 100 items.
[junit4] 2> 377804 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.h.c.SuggestComponent SuggestComponent process with : qt=/suggest_analyzing_infix_short_dictionary&suggest=true&suggest.buildAll=true&wt=xml&indent=off
[junit4] 2> 377804 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/suggest_analyzing_infix_short_dictionary&suggest.buildAll=true&wt=xml} status=0 QTime=86
[junit4] 2> 377805 ERROR (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties
[junit4] 2> 377811 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 377825 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 377833 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 377935 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 377943 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 377965 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ 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@499864a6
[junit4] 2> 377965 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.InfixSuggestersTest_216495DF9F0D91A7-001/init-core-data-001/]
[junit4] 2> 377998 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 377998 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 377999 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@688affe1[collection1] main]
[junit4] 2> 377999 WARN (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 377999 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 377999 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378014 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378014 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 378014 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378014 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378014 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 378014 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 378014 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 378014 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 378015 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=16.955078125, floorSegmentMB=1.08984375, forceMergeDeletesPctAllowed=14.469083610543857, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=40.53349792276117
[junit4] 2> 378015 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 378016 INFO (searcherExecutor-1821-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@688affe1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 378017 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b9de355[collection1] main]
[junit4] 2> 378017 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6f22757b
[junit4] 2> 378017 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=6f22757b
[junit4] 2> 378017 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b55d78d: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@654b0802
[junit4] 2> 378018 INFO (searcherExecutor-1821-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1b9de355[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 378023 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent SuggestComponent prepare with : qt=/suggest_analyzing_infix_short_dictionary&suggest=true&suggest.buildAll=true&wt=xml&indent=off
[junit4] 2> 378023 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester SolrSuggester.build(shortRandomAnalyzingInfixSuggester)
[junit4] 2> 378034 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.RandomTestDictionaryFactory org.apache.solr.spelling.suggest.RandomTestDictionaryFactory$RandomTestDictionary.enabled.shortRandomAnalyzingInfixSuggester disabled after emitting 100 items.
[junit4] 2> 378087 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent SuggestComponent process with : qt=/suggest_analyzing_infix_short_dictionary&suggest=true&suggest.buildAll=true&wt=xml&indent=off
[junit4] 2> 378087 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/suggest_analyzing_infix_short_dictionary&suggest.buildAll=true&wt=xml} status=0 QTime=64
[junit4] 2> 378088 ERROR (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties
[junit4] 2> 378088 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 378101 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 378111 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 378208 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 378215 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 378239 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ 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@499864a6
[junit4] 2> 378239 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.InfixSuggestersTest_216495DF9F0D91A7-001/init-core-data-001/]
[junit4] 2> 378279 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 378279 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 378280 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2dc105d8[collection1] main]
[junit4] 2> 378281 WARN (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 378281 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 378281 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378282 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378282 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 378282 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378282 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378282 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 378282 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 378282 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 378282 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 378283 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=12.0400390625, floorSegmentMB=1.2373046875, forceMergeDeletesPctAllowed=18.38284303361402, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=46.93522828897392
[junit4] 2> 378283 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 378284 INFO (searcherExecutor-1825-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2dc105d8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 378284 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@31d94ff1[collection1] main]
[junit4] 2> 378284 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5f4e426a
[junit4] 2> 378284 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=5f4e426a
[junit4] 2> 378284 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@9273ac1: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@654b0802
[junit4] 2> 378285 INFO (searcherExecutor-1825-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@31d94ff1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 378293 INFO (TEST-InfixSuggestersTest.test2xBuildReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending test2xBuildReload
[junit4] 2> 378295 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testAnalyzingInfixSuggesterBuildThenReload
[junit4] 2> 378295 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent SuggestComponent prepare with : qt=/suggest_analyzing_infix_short_dictionary&suggest=true&suggest.buildAll=true&wt=xml&indent=off
[junit4] 2> 378295 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester SolrSuggester.build(shortRandomAnalyzingInfixSuggester)
[junit4] 2> 378305 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.RandomTestDictionaryFactory org.apache.solr.spelling.suggest.RandomTestDictionaryFactory$RandomTestDictionary.enabled.shortRandomAnalyzingInfixSuggester disabled after emitting 100 items.
[junit4] 2> 378344 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent SuggestComponent process with : qt=/suggest_analyzing_infix_short_dictionary&suggest=true&suggest.buildAll=true&wt=xml&indent=off
[junit4] 2> 378345 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/suggest_analyzing_infix_short_dictionary&suggest.buildAll=true&wt=xml} status=0 QTime=49
[junit4] 2> 378345 ERROR (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties
[junit4] 2> 378345 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 378360 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 378369 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 378457 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 378464 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 378484 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ 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@499864a6
[junit4] 2> 378484 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.InfixSuggestersTest_216495DF9F0D91A7-001/init-core-data-001/]
[junit4] 2> 378534 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 378534 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 378535 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@624f347[collection1] main]
[junit4] 2> 378536 WARN (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 378536 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 378536 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378537 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378537 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 378537 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378537 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378537 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 378537 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 378537 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 378537 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 378547 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7931687335938498]
[junit4] 2> 378548 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 378549 INFO (searcherExecutor-1829-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@624f347[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 378549 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@55c7904f[collection1] main]
[junit4] 2> 378549 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@41ff8960
[junit4] 2> 378549 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=41ff8960
[junit4] 2> 378549 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@45181c45: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@654b0802
[junit4] 2> 378550 INFO (searcherExecutor-1829-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@55c7904f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 378556 INFO (TEST-InfixSuggestersTest.testAnalyzingInfixSuggesterBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testAnalyzingInfixSuggesterBuildThenReload
[junit4] 2> 378557 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testBuildThen2xReload
[junit4] 2> 378557 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent SuggestComponent prepare with : qt=/suggest_analyzing_infix_short_dictionary&suggest=true&suggest.buildAll=true&wt=xml&indent=off
[junit4] 2> 378557 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester SolrSuggester.build(shortRandomAnalyzingInfixSuggester)
[junit4] 2> 378564 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.RandomTestDictionaryFactory org.apache.solr.spelling.suggest.RandomTestDictionaryFactory$RandomTestDictionary.enabled.shortRandomAnalyzingInfixSuggester disabled after emitting 100 items.
[junit4] 2> 378609 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent SuggestComponent process with : qt=/suggest_analyzing_infix_short_dictionary&suggest=true&suggest.buildAll=true&wt=xml&indent=off
[junit4] 2> 378609 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/suggest_analyzing_infix_short_dictionary&suggest.buildAll=true&wt=xml} status=0 QTime=51
[junit4] 2> 378609 ERROR (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties
[junit4] 2> 378609 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 378623 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 378635 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 378718 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 378726 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 378754 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ 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@499864a6
[junit4] 2> 378754 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.InfixSuggestersTest_216495DF9F0D91A7-001/init-core-data-001/]
[junit4] 2> 378800 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 378800 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 378802 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a59aa98[collection1] main]
[junit4] 2> 378802 WARN (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 378802 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 378802 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378803 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378803 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 378803 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378804 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 378804 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 378804 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 378804 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 378804 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 378805 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=25, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=23.5947265625, floorSegmentMB=0.3544921875, forceMergeDeletesPctAllowed=18.674161035667638, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=33.429083236368555
[junit4] 2> 378805 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 378805 INFO (searcherExecutor-1833-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5a59aa98[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 378805 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7121be4e[collection1] main]
[junit4] 2> 378806 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4651fdc9
[junit4] 2> 378806 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=4651fdc9
[junit4] 2> 378806 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@61247504: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@654b0802
[junit4] 2> 378806 INFO (searcherExecutor-1833-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7121be4e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 378815 ERROR (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties
[junit4] 2> 378816 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 378828 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 378837 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 378925 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 378932 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 378960 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ 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@499864a6
[junit4] 2> 378960 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.InfixSuggestersTest_216495DF9F0D91A7-001/init-core-data-001/]
[junit4] 2> 379010 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 379010 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 379011 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6ab9117f[collection1] main]
[junit4] 2> 379012 WARN (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 379012 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 379012 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 379013 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 379013 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 379013 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 379014 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 379014 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 379014 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 379014 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 379014 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 379015 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=91.0732421875, floorSegmentMB=1.9521484375, forceMergeDeletesPctAllowed=24.845676602570947, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12013201670439982, deletesPctAllowed=35.0515672139154
[junit4] 2> 379015 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 379016 INFO (searcherExecutor-1837-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6ab9117f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 379016 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@64fb2460[collection1] main]
[junit4] 2> 379016 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5337a51a
[junit4] 2> 379016 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=5337a51a
[junit4] 2> 379016 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@31a3041b: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@654b0802
[junit4] 2> 379017 INFO (searcherExecutor-1837-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@64fb2460[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 379023 INFO (TEST-InfixSuggestersTest.testBuildThen2xReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testBuildThen2xReload
[junit4] 2> 379024 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testShutdownDuringBuild
[junit4] 2> 379025 INFO (AnalyzingInfixSuggesterTest-1231-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SuggestComponent SuggestComponent prepare with : qt=/suggest_analyzing_infix_long_dictionary&suggest=true&suggest.buildAll=true&wt=xml&indent=off
[junit4] 2> 379025 INFO (AnalyzingInfixSuggesterTest-1231-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.SolrSuggester SolrSuggester.build(longRandomAnalyzingInfixSuggester)
[junit4] 2> 379088 INFO (AnalyzingInfixSuggesterTest-1231-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.RandomTestDictionaryFactory org.apache.solr.spelling.suggest.RandomTestDictionaryFactory$RandomTestDictionary.enabled.longRandomAnalyzingInfixSuggester emitted 1000 items.
[junit4] 2> 379125 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1083186881
[junit4] 2> 379125 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 379125 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6932850e: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@34126468
[junit4] 2> 379128 INFO (AnalyzingInfixSuggesterTest-1231-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.RandomTestDictionaryFactory org.apache.solr.spelling.suggest.RandomTestDictionaryFactory$RandomTestDictionary.enabled.longRandomAnalyzingInfixSuggester emitted 2000 items.
[junit4] 2> 379131 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 379131 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3a69e22d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@31feb494
[junit4] 2> 379134 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 379134 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@43429889: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4879213d
[junit4] 2> 379138 INFO (coreCloseExecutor-1841-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3fc9a2ba
[junit4] 2> 379138 INFO (coreCloseExecutor-1841-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=3fc9a2ba
[junit4] 2> 379138 INFO (coreCloseExecutor-1841-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4c826a0e: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@654b0802
[junit4] 2> 379294 ERROR (AnalyzingInfixSuggesterTest-1231-thread-1-processing-x:collection1) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: qt=/suggest_analyzing_infix_long_dictionary&suggest.buildAll=true&wt=xml:org.apache.solr.common.SolrException: SolrCoreState already closed.
[junit4] 2> at org.apache.solr.update.DefaultSolrCoreState.lock(DefaultSolrCoreState.java:186)
[junit4] 2> at org.apache.solr.update.DefaultSolrCoreState.getIndexWriter(DefaultSolrCoreState.java:121)
[junit4] 2> at org.apache.solr.update.SolrCoreState.getTragicException(SolrCoreState.java:198)
[junit4] 2> at org.apache.solr.core.CoreContainer.checkTragicException(CoreContainer.java:1812)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:212)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2541)
[junit4] 2> at org.apache.solr.util.TestHarness.query(TestHarness.java:337)
[junit4] 2> at org.apache.solr.util.TestHarness.query(TestHarness.java:319)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:891)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:877)
[junit4] 2> at org.apache.solr.handler.component.InfixSuggestersTest.lambda$testShutdownDuringBuild$2(InfixSuggestersTest.java:122)
[junit4] 2> at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2726)
[junit4] 2> at org.apache.solr.handler.component.InfixSuggestersTest.lambda$testShutdownDuringBuild$3(InfixSuggestersTest.java:121)
[junit4] 2> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1135)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 379394 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 379395 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 379408 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 379417 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 379484 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 379597 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 379598 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 379611 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@499864a6
[junit4] 2> 379619 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@499864a6
[junit4] 2> 379619 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@499864a6
[junit4] 2> 379622 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 379645 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 379655 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 379728 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 379744 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 379744 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ 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@499864a6
[junit4] 2> 379744 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 379744 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.InfixSuggestersTest_216495DF9F0D91A7-001/init-core-data-001/]
[junit4] 2> 379796 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 379796 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 379798 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 379799 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3d8ec9f4[collection1] main]
[junit4] 2> 379799 WARN (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 379800 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 379800 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 379801 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,indexPath=short_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 379801 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 379801 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 379802 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=longRandomAnalyzingInfixSuggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 379802 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 379802 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 379803 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 379803 INFO (coreLoadExecutor-1851-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 379803 INFO (searcherExecutor-1852-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3d8ec9f4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 379804 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 379805 INFO (TEST-InfixSuggestersTest.testShutdownDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testShutdownDuringBuild
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=InfixSuggestersTest -Dtests.method=testShutdownDuringBuild -Dtests.seed=216495DF9F0D91A7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mas -Dtests.timezone=America/Argentina/La_Rioja -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.79s J1 | InfixSuggestersTest.testShutdownDuringBuild <<<
[junit4] > Throwable #1: java.util.concurrent.ExecutionException: junit.framework.AssertionFailedError: Unexpected wrapped exception type, expected CoreIsClosedException
[junit4] > at __randomizedtesting.SeedInfo.seed([216495DF9F0D91A7:FEE9F760A164C4C5]:0)
[junit4] > at java.base/java.util.concurrent.FutureTask.report(FutureTask.java:122)
[junit4] > at java.base/java.util.concurrent.FutureTask.get(FutureTask.java:191)
[junit4] > at org.apache.solr.handler.component.InfixSuggestersTest.testShutdownDuringBuild(InfixSuggestersTest.java:130)
[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:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] > Caused by: junit.framework.AssertionFailedError: Unexpected wrapped exception type, expected CoreIsClosedException
[junit4] > at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2734)
[junit4] > at org.apache.solr.handler.component.InfixSuggestersTest.lambda$testShutdownDuringBuild$3(InfixSuggestersTest.java:121)
[junit4] > at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1135)
[junit4] > at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
[junit4] > ... 1 more
[junit4] > Caused by: java.lang.RuntimeException: Exception during query
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:917)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:877)
[junit4] > at org.apache.solr.handler.component.InfixSuggestersTest.lambda$testShutdownDuringBuild$2(InfixSuggestersTest.java:122)
[junit4] > at org.apache.lucene.util.LuceneTestCase.expectThrows(LuceneTestCase.java:2726)
[junit4] > ... 6 more
[junit4] > Caused by: org.apache.solr.common.SolrException: SolrCoreState already closed.
[junit4] > at org.apache.solr.update.DefaultSolrCoreState.lock(DefaultSolrCoreState.java:186)
[junit4] > at org.apache.solr.update.DefaultSolrCoreState.getIndexWriter(DefaultSolrCoreState.java:121)
[junit4] > at org.apache.solr.update.SolrCoreState.getTragicException(SolrCoreState.java:198)
[junit4] > at org.apache.solr.core.CoreContainer.checkTragicException(CoreContainer.java:1812)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:212)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2541)
[junit4] > at org.apache.solr.util.TestHarness.query(TestHarness.java:337)
[junit4] > at org.apache.solr.util.TestHarness.query(TestHarness.java:319)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:891)
[junit4] > ... 9 more
[junit4] 2> 379810 INFO (TEST-InfixSuggestersTest.testReloadDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testReloadDuringBuild
[junit4] 2> 379822 ERROR (TEST-InfixSuggestersTest.testReloadDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.CorePropertiesLocator Couldn't load core descriptor from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties:java.nio.file.NoSuchFileException: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/core.properties
[junit4] 2> 379822 INFO (AnalyzingInfixSuggesterTest-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SuggestComponent SuggestComponent prepare with : qt=/suggest_analyzing_infix_long_dictionary&suggest=true&suggest.buildAll=true&wt=xml&indent=off
[junit4] 2> 379822 INFO (AnalyzingInfixSuggesterTest-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.SolrSuggester SolrSuggester.build(longRandomAnalyzingInfixSuggester)
[junit4] 2> 379824 INFO (TEST-InfixSuggestersTest.testReloadDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 379867 INFO (TEST-InfixSuggestersTest.testReloadDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 379901 INFO (TEST-InfixSuggestersTest.testReloadDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 379947 INFO (AnalyzingInfixSuggesterTest-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.RandomTestDictionaryFactory org.apache.solr.spelling.suggest.RandomTestDictionaryFactory$RandomTestDictionary.enabled.longRandomAnalyzingInfixSuggester emitted 1000 items.
[junit4] 2> 380053 INFO (AnalyzingInfixSuggesterTest-1234-thread-1-processing-x:collection1) [ x:collection1] o.a.s.s.s.RandomTestDictionaryFactory org.apache.solr.spelling.suggest.RandomTestDictionaryFactory$RandomTestDictionary.enabled.longRandomAnalyzingInfixSuggester emitted 2000 items.
[junit4] 2> 380056 INFO (TEST-InfixSuggestersTest.testReloadDuringBuild-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[...truncated too long message...]
ggester,lookupImpl=AnalyzingInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100000,indexPath=long_random_analyzing_infix_suggester,suggestAnalyzerFieldType=text,buildOnCommit=false,buildOnStartup=false}
[junit4] 2> 381143 INFO (TEST-InfixSuggestersTest.testTwoSuggestersBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 381143 INFO (TEST-InfixSuggestersTest.testTwoSuggestersBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester init: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 381143 INFO (TEST-InfixSuggestersTest.testTwoSuggestersBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params: {name=shortRandomBlendedInfixSuggester,lookupImpl=BlendedInfixLookupFactory,dictionaryImpl=RandomTestDictionaryFactory,randDictMaxItems=100,blenderType=position_linear,suggestAnalyzerFieldType=text,indexPath=short_random_blended_infix_suggester,highlight=true,buildOnStartup=false,buildOnCommit=false}
[junit4] 2> 381143 INFO (TEST-InfixSuggestersTest.testTwoSuggestersBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 381144 INFO (TEST-InfixSuggestersTest.testTwoSuggestersBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=17.6435546875, floorSegmentMB=0.46484375, forceMergeDeletesPctAllowed=8.424187210699603, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19712846952833518, deletesPctAllowed=48.53050614325616
[junit4] 2> 381144 INFO (TEST-InfixSuggestersTest.testTwoSuggestersBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 381145 INFO (searcherExecutor-1868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@13291caf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 381145 INFO (TEST-InfixSuggestersTest.testTwoSuggestersBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2667d661[collection1] main]
[junit4] 2> 381145 INFO (TEST-InfixSuggestersTest.testTwoSuggestersBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7da3453
[junit4] 2> 381145 INFO (TEST-InfixSuggestersTest.testTwoSuggestersBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=7da3453
[junit4] 2> 381145 INFO (TEST-InfixSuggestersTest.testTwoSuggestersBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@52eeee8e: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@12aae2f3
[junit4] 2> 381146 INFO (searcherExecutor-1868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2667d661[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 381152 INFO (TEST-InfixSuggestersTest.testTwoSuggestersBuildThenReload-seed#[216495DF9F0D91A7]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testTwoSuggestersBuildThenReload
[junit4] 2> 381152 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 381152 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=669364004
[junit4] 2> 381153 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 381153 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@51f8581d: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@455078fe
[junit4] 2> 381156 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 381156 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@46d8063f: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@31feb494
[junit4] 2> 381159 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 381159 INFO (SUITE-InfixSuggestersTest-seed#[216495DF9F0D91A7]-worker) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7e6a1815: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@4879213d
[junit4] 2> 381160 INFO (coreCloseExecutor-1872-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4e0a5f03
[junit4] 2> 381160 INFO (coreCloseExecutor-1872-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=4e0a5f03
[junit4] 2> 381160 INFO (coreCloseExecutor-1872-thread-1-processing-x:collection1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@61373304: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@12aae2f3
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.InfixSuggestersTest_216495DF9F0D91A7-001
[junit4] 2> Jul 25, 2018 9:04:13 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=6, maxDocsPerChunk=707, blockSize=8), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=6, blockSize=8)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@226231ed), locale=mas, timezone=America/Argentina/La_Rioja
[junit4] 2> NOTE: Linux 4.15.0-24-generic amd64/Oracle Corporation 10 (64-bit)/cpus=8,threads=1,free=182074128,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [SystemInfoHandlerTest, NumberUtilsTest, TestSkipOverseerOperations, ClusterStateTest, CoreAdminHandlerTest, UpdateParamsTest, TaggingAttributeTest, UpdateRequestProcessorFactoryTest, TestLFUCache, QueryParsingTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TermsComponentTest, TestMinMaxOnMultiValuedField, TestOnReconnectListenerSupport, TestComplexPhraseQParserPlugin, HdfsDirectoryTest, TestCustomStream, SolrInfoBeanTest, TestSort, ParsingFieldUpdateProcessorsTest, HdfsBasicDistributedZkTest, TestJsonFacetRefinement, MinimalSchemaTest, TestSolrDeletionPolicy2, TestStressVersions, TestCoreContainer, TestPullReplicaErrorHandling, URLClassifyProcessorTest, TestUseDocValuesAsStored2, TestSolrConfigHandlerCloud, DistributedFacetSimpleRefinementLongTailTest, InfixSuggestersTest]
[junit4] Completed [139/826 (1!)] on J1 in 4.55s, 7 tests, 1 error <<< FAILURES!
[...truncated 44427 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22526/consoleText
[repro] Revision: 71c0bddd149b7c0364fbba8d31494dcd9f57f1ef
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=InfixSuggestersTest -Dtests.method=testShutdownDuringBuild -Dtests.seed=216495DF9F0D91A7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mas -Dtests.timezone=America/Argentina/La_Rioja -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] InfixSuggestersTest
[repro] ant compile-test
[...truncated 3323 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.InfixSuggestersTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=216495DF9F0D91A7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mas -Dtests.timezone=America/Argentina/La_Rioja -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 106 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.handler.component.InfixSuggestersTest
[repro] Exiting with code 0
[...truncated 40 lines...]