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/04/08 04:35:19 UTC
[JENKINS] Lucene-Solr-7.x-MacOSX (64bit/jdk-9) - Build # 564 -
Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/564/
Java: 64bit/jdk-9 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.test
Error Message:
expected:<4> but was:<3>
Stack Trace:
java.lang.AssertionError: expected:<4> but was:<3>
at __randomizedtesting.SeedInfo.seed([A17AE08A339277C0:292EDF509D6E1A38]: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.update.processor.TimeRoutedAliasUpdateProcessorTest.assertInvariants(TimeRoutedAliasUpdateProcessorTest.java:343)
at org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.test(TimeRoutedAliasUpdateProcessorTest.java:169)
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 14852 lines...]
[junit4] Suite: org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest
[junit4] 2> 3233353 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[A17AE08A339277C0]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/init-core-data-001
[junit4] 2> 3233353 WARN (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[A17AE08A339277C0]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=358 numCloses=358
[junit4] 2> 3233353 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[A17AE08A339277C0]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 3233354 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[A17AE08A339277C0]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 3233354 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[A17AE08A339277C0]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001
[junit4] 2> 3233354 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[A17AE08A339277C0]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3233355 INFO (Thread-6501) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3233355 INFO (Thread-6501) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3233358 ERROR (Thread-6501) [ ] 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> 3233459 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[A17AE08A339277C0]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:56556
[junit4] 2> 3233467 INFO (zkConnectionManagerCallback-10751-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3233472 INFO (jetty-launcher-10748-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T06:57:37+09:30, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 3233472 INFO (jetty-launcher-10748-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T06:57:37+09:30, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 3233474 INFO (jetty-launcher-10748-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3233474 INFO (jetty-launcher-10748-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3233474 INFO (jetty-launcher-10748-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 3233474 INFO (jetty-launcher-10748-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@235a3dee{/solr,null,AVAILABLE}
[junit4] 2> 3233474 INFO (jetty-launcher-10748-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3233474 INFO (jetty-launcher-10748-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3233474 INFO (jetty-launcher-10748-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1808e134{HTTP/1.1,[http/1.1]}{127.0.0.1:56558}
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@46554000{/solr,null,AVAILABLE}
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-2) [ ] o.e.j.s.Server Started @3233566ms
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56558}
[junit4] 2> 3233475 ERROR (jetty-launcher-10748-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@718d3f6f{HTTP/1.1,[http/1.1]}{127.0.0.1:56559}
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-1) [ ] o.e.j.s.Server Started @3233566ms
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56559}
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0
[junit4] 2> 3233475 ERROR (jetty-launcher-10748-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-08T04:17:37.766700Z
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.4.0
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 3233475 INFO (jetty-launcher-10748-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-08T04:17:37.766812Z
[junit4] 2> 3233477 INFO (zkConnectionManagerCallback-10753-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3233477 INFO (zkConnectionManagerCallback-10755-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3233477 INFO (jetty-launcher-10748-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3233477 INFO (jetty-launcher-10748-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3233483 INFO (jetty-launcher-10748-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56556/solr
[junit4] 2> 3233483 INFO (jetty-launcher-10748-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56556/solr
[junit4] 2> 3233485 INFO (zkConnectionManagerCallback-10761-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3233485 INFO (zkConnectionManagerCallback-10763-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3233488 INFO (zkConnectionManagerCallback-10767-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3233488 INFO (zkConnectionManagerCallback-10765-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3233634 INFO (jetty-launcher-10748-thread-1) [n:127.0.0.1:56559_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3233634 INFO (jetty-launcher-10748-thread-2) [n:127.0.0.1:56558_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 3233634 INFO (jetty-launcher-10748-thread-1) [n:127.0.0.1:56559_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56559_solr
[junit4] 2> 3233635 INFO (jetty-launcher-10748-thread-2) [n:127.0.0.1:56558_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56558_solr
[junit4] 2> 3233636 INFO (jetty-launcher-10748-thread-1) [n:127.0.0.1:56559_solr ] o.a.s.c.Overseer Overseer (id=72085559722639366-127.0.0.1:56559_solr-n_0000000000) starting
[junit4] 2> 3233637 INFO (zkCallback-10766-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3233637 INFO (zkCallback-10764-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3233647 INFO (zkConnectionManagerCallback-10776-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3233648 INFO (jetty-launcher-10748-thread-1) [n:127.0.0.1:56559_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3233650 INFO (jetty-launcher-10748-thread-1) [n:127.0.0.1:56559_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56556/solr ready
[junit4] 2> 3233654 INFO (jetty-launcher-10748-thread-1) [n:127.0.0.1:56559_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3233654 INFO (jetty-launcher-10748-thread-1) [n:127.0.0.1:56559_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56559_solr
[junit4] 2> 3233656 INFO (zkCallback-10766-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3233657 INFO (zkCallback-10775-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3233657 INFO (zkCallback-10764-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3233781 INFO (jetty-launcher-10748-thread-2) [n:127.0.0.1:56558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56558.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1513223a
[junit4] 2> 3233789 INFO (jetty-launcher-10748-thread-2) [n:127.0.0.1:56558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56558.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1513223a
[junit4] 2> 3233789 INFO (jetty-launcher-10748-thread-2) [n:127.0.0.1:56558_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56558.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1513223a
[junit4] 2> 3233790 INFO (jetty-launcher-10748-thread-2) [n:127.0.0.1:56558_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/.
[junit4] 2> 3233797 INFO (zkConnectionManagerCallback-10781-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3233799 INFO (jetty-launcher-10748-thread-2) [n:127.0.0.1:56558_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 3233800 INFO (jetty-launcher-10748-thread-2) [n:127.0.0.1:56558_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56556/solr ready
[junit4] 2> 3233849 INFO (jetty-launcher-10748-thread-1) [n:127.0.0.1:56559_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56559.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1513223a
[junit4] 2> 3233857 INFO (jetty-launcher-10748-thread-1) [n:127.0.0.1:56559_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56559.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1513223a
[junit4] 2> 3233857 INFO (jetty-launcher-10748-thread-1) [n:127.0.0.1:56559_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56559.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1513223a
[junit4] 2> 3233858 INFO (jetty-launcher-10748-thread-1) [n:127.0.0.1:56559_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/.
[junit4] 2> 3233881 INFO (zkConnectionManagerCallback-10783-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3233903 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[A17AE08A339277C0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testParse
[junit4] 2> 3233904 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.testParse-seed#[A17AE08A339277C0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testParse
[junit4] 2> 3233918 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.test-seed#[A17AE08A339277C0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 3233920 INFO (zkConnectionManagerCallback-10789-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3233922 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.test-seed#[A17AE08A339277C0]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 3233924 INFO (TEST-TimeRoutedAliasUpdateProcessorTest.test-seed#[A17AE08A339277C0]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56556/solr ready
[junit4] 2> 3233927 INFO (qtp219863730-35845) [n:127.0.0.1:56558_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :create with params baseConfigSet=_default&name=timeConfig&action=CREATE&wt=javabin&version=2
[junit4] 2> 3233930 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.OverseerConfigSetMessageHandler OverseerConfigSetMessageHandler.processMessage : create , {
[junit4] 2> "name":"timeConfig",
[junit4] 2> "baseConfigSet":"_default",
[junit4] 2> "immutable":"false",
[junit4] 2> "operation":"configsets:create"}
[junit4] 2> 3233932 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/timeConfig/managed-schema
[junit4] 2> 3233935 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/timeConfig/protwords.txt
[junit4] 2> 3233938 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/timeConfig/solrconfig.xml
[junit4] 2> 3233941 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/timeConfig/synonyms.txt
[junit4] 2> 3233944 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/timeConfig/stopwords.txt
[junit4] 2> 3233947 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/timeConfig/lang/contractions_it.txt
[junit4] 2> 3233950 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/timeConfig/lang/contractions_ca.txt
[junit4] 2> 3233953 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/timeConfig/lang/stemdict_nl.txt
[junit4] 2> 3233957 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/timeConfig/lang/stopwords_hy.txt
[junit4] 2> 3233960 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/timeConfig/lang/stopwords_no.txt
[junit4] 2> 3233963 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/timeConfig/lang/stopwords_id.txt
[junit4] 2> 3233966 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/timeConfig/lang/stopwords_da.txt
[junit4] 2> 3233969 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/timeConfig/lang/stopwords_ga.txt
[junit4] 2> 3233972 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/timeConfig/lang/stopwords_hi.txt
[junit4] 2> 3233975 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/timeConfig/lang/stopwords_ja.txt
[junit4] 2> 3233978 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/timeConfig/lang/stopwords_pt.txt
[junit4] 2> 3233981 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/timeConfig/lang/stopwords_hu.txt
[junit4] 2> 3233984 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/timeConfig/lang/stopwords_el.txt
[junit4] 2> 3233987 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/timeConfig/lang/stopwords_ru.txt
[junit4] 2> 3233990 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/timeConfig/lang/stopwords_tr.txt
[junit4] 2> 3233994 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/timeConfig/lang/stopwords_ar.txt
[junit4] 2> 3233997 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/timeConfig/lang/userdict_ja.txt
[junit4] 2> 3234000 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/timeConfig/lang/stopwords_eu.txt
[junit4] 2> 3234002 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/timeConfig/lang/stopwords_it.txt
[junit4] 2> 3234007 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/timeConfig/lang/stopwords_cz.txt
[junit4] 2> 3234010 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/timeConfig/lang/stoptags_ja.txt
[junit4] 2> 3234014 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/timeConfig/lang/contractions_fr.txt
[junit4] 2> 3234018 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/timeConfig/lang/stopwords_de.txt
[junit4] 2> 3234022 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/timeConfig/lang/stopwords_fa.txt
[junit4] 2> 3234025 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/timeConfig/lang/contractions_ga.txt
[junit4] 2> 3234029 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/timeConfig/lang/stopwords_ca.txt
[junit4] 2> 3234032 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/timeConfig/lang/stopwords_nl.txt
[junit4] 2> 3234036 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/timeConfig/lang/stopwords_bg.txt
[junit4] 2> 3234040 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/timeConfig/lang/stopwords_en.txt
[junit4] 2> 3234043 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/timeConfig/lang/stopwords_es.txt
[junit4] 2> 3234046 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/timeConfig/lang/stopwords_lv.txt
[junit4] 2> 3234049 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/timeConfig/lang/stopwords_ro.txt
[junit4] 2> 3234053 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/timeConfig/lang/stopwords_th.txt
[junit4] 2> 3234056 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/timeConfig/lang/stopwords_fr.txt
[junit4] 2> 3234059 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/timeConfig/lang/stopwords_sv.txt
[junit4] 2> 3234062 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/timeConfig/lang/stopwords_fi.txt
[junit4] 2> 3234066 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/timeConfig/lang/hyphenations_ga.txt
[junit4] 2> 3234070 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/timeConfig/lang/stopwords_gl.txt
[junit4] 2> 3234073 INFO (OverseerThreadFactory-14304-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/timeConfig/params.json
[junit4] 2> 3234078 INFO (qtp219863730-35845) [n:127.0.0.1:56558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/configs params={baseConfigSet=_default&name=timeConfig&action=CREATE&wt=javabin&version=2} status=0 QTime=151
[junit4] 2> 3234079 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=timeConfig&name=timeConfig&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3234085 INFO (OverseerThreadFactory-14304-thread-2) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection timeConfig
[junit4] 2> 3234093 INFO (OverseerCollectionConfigSetProcessor-72085559722639366-127.0.0.1:56559_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 3234237 INFO (OverseerStateUpdate-72085559722639366-127.0.0.1:56559_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"timeConfig",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"timeConfig_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:56558/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3234447 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=timeConfig&newCollection=true&name=timeConfig_shard1_replica_n1&action=CREATE&numShards=1&collection=timeConfig&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 3234447 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3234564 INFO (zkCallback-10766-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/timeConfig/state.json] for collection [timeConfig] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3234564 INFO (zkCallback-10766-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/timeConfig/state.json] for collection [timeConfig] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3235461 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 3235461 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 3235462 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 3235462 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist
[junit4] 2> 3235462 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 3235462 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist
[junit4] 2> 3235462 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 3235462 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist
[junit4] 2> 3235462 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 3235462 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist
[junit4] 2> 3235467 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 3235482 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.IndexSchema [timeConfig_shard1_replica_n1] Schema name=default-config
[junit4] 2> 3235639 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 3235694 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'timeConfig_shard1_replica_n1' using configuration from collection timeConfig, trusted=true
[junit4] 2> 3235695 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56558.solr.core.timeConfig.shard1.replica_n1' (registry 'solr.core.timeConfig.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1513223a
[junit4] 2> 3235695 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 3235695 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrCore [[timeConfig_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/./timeConfig_shard1_replica_n1/data/]
[junit4] 2> 3235700 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 3235781 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3235781 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3235782 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 3235782 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3235784 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4bdf6587[timeConfig_shard1_replica_n1] main]
[junit4] 2> 3235785 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/timeConfig
[junit4] 2> 3235786 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/timeConfig
[junit4] 2> 3235786 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/timeConfig/managed-schema
[junit4] 2> 3235787 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3235787 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 3235788 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 3235789 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3235789 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1597150131734446080
[junit4] 2> 3235789 INFO (searcherExecutor-14309-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@4bdf6587[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3235789 INFO (searcherExecutor-14309-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 3235789 INFO (searcherExecutor-14309-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 3235790 INFO (searcherExecutor-14309-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrCore [timeConfig_shard1_replica_n1] Registered new searcher Searcher@4bdf6587[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3235796 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/timeConfig/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 3235801 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 3235801 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 3235801 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56558/solr/timeConfig_shard1_replica_n1/
[junit4] 2> 3235801 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 3235801 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:56558/solr/timeConfig_shard1_replica_n1/ has no replicas
[junit4] 2> 3235801 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 3235806 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:56558/solr/timeConfig_shard1_replica_n1/ shard1
[junit4] 2> 3235913 INFO (zkCallback-10766-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/timeConfig/state.json] for collection [timeConfig] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3235913 INFO (zkCallback-10766-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/timeConfig/state.json] for collection [timeConfig] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3235968 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 3235971 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=timeConfig&newCollection=true&name=timeConfig_shard1_replica_n1&action=CREATE&numShards=1&collection=timeConfig&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1524
[junit4] 2> 3235975 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 3236072 INFO (zkCallback-10766-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/timeConfig/state.json] for collection [timeConfig] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3236072 INFO (zkCallback-10766-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/timeConfig/state.json] for collection [timeConfig] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3236099 INFO (OverseerCollectionConfigSetProcessor-72085559722639366-127.0.0.1:56559_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 3236977 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=timeConfig&name=timeConfig&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2897
[junit4] 2> 3236982 INFO (qtp219863730-35850) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Executed config commands successfully and persisted to ZK [{"set-user-property":{"update.autoCreateFields":false}}, {"add-updateprocessor":{
[junit4] 2> "name":"tolerant",
[junit4] 2> "class":"solr.TolerantUpdateProcessorFactory"}}, {"add-updateprocessor":{
[junit4] 2> "name":"inc",
[junit4] 2> "class":"org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest$IncrementURPFactory",
[junit4] 2> "fieldName":"integer_i"}}]
[junit4] 2> 3236982 INFO (qtp219863730-35850) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 1 replicas to set the property overlay to be of version 0 for collection timeConfig
[junit4] 2> 3236983 INFO (Thread-6520) [ ] o.a.s.c.SolrCore config update listener called for core timeConfig_shard1_replica_n1
[junit4] 2> 3236983 INFO (solrHandlerExecutor-14314-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 3236984 INFO (Thread-6520) [ ] o.a.s.c.SolrCore core reload timeConfig_shard1_replica_n1
[junit4] 2> 3236992 INFO (Thread-6520) [ ] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 3236992 INFO (Thread-6520) [ ] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 3236992 WARN (Thread-6520) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 3236993 WARN (Thread-6520) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist
[junit4] 2> 3236993 WARN (Thread-6520) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 3236993 WARN (Thread-6520) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist
[junit4] 2> 3236993 WARN (Thread-6520) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 3236993 WARN (Thread-6520) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist
[junit4] 2> 3236993 WARN (Thread-6520) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 3236993 WARN (Thread-6520) [ ] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1/../../../../dist
[junit4] 2> 3236997 INFO (Thread-6520) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 3237011 INFO (Thread-6520) [ ] o.a.s.s.IndexSchema [timeConfig_shard1_replica_n1] Schema name=default-config
[junit4] 2> 3237086 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 3237086 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.S.Request [timeConfig_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3237086 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 3237087 INFO (solrHandlerExecutor-14314-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from http://127.0.0.1:56558/solr/timeConfig_shard1_replica_n1/ for prop overlay after 1 attempts
[junit4] 2> 3237087 INFO (solrHandlerExecutor-14314-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 3237155 INFO (Thread-6520) [ ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 3237189 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 3237189 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.S.Request [timeConfig_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3237189 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 3237189 INFO (solrHandlerExecutor-14314-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from http://127.0.0.1:56558/solr/timeConfig_shard1_replica_n1/ for prop overlay after 2 attempts
[junit4] 2> 3237189 INFO (solrHandlerExecutor-14314-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 3237205 INFO (Thread-6520) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'timeConfig_shard1_replica_n1' using configuration from collection timeConfig
[junit4] 2> 3237225 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56558.solr.core.timeConfig.shard1.replica_n1' (registry 'solr.core.timeConfig.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1513223a
[junit4] 2> 3237225 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrCore [[timeConfig_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/timeConfig_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/./timeConfig_shard1_replica_n1/data/]
[junit4] 2> 3237229 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 3237268 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms;
[junit4] 2> 3237268 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3237270 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5198bf7[timeConfig_shard1_replica_n1] main]
[junit4] 2> 3237271 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/timeConfig
[junit4] 2> 3237272 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/timeConfig
[junit4] 2> 3237272 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/timeConfig/managed-schema
[junit4] 2> 3237273 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 3237273 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 3237273 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 3237274 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3237274 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1597150133291581440
[junit4] 2> 3237274 INFO (searcherExecutor-14315-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@5198bf7[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3237274 INFO (searcherExecutor-14315-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 3237274 INFO (searcherExecutor-14315-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 3237276 INFO (searcherExecutor-14315-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrCore [timeConfig_shard1_replica_n1] Registered new searcher Searcher@5198bf7[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3237276 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 3237277 INFO (Thread-6520) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@65928cb0[timeConfig_shard1_replica_n1] main]
[junit4] 2> 3237277 INFO (searcherExecutor-14315-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@65928cb0[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3237277 INFO (searcherExecutor-14315-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 3237279 INFO (searcherExecutor-14315-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrCore [timeConfig_shard1_replica_n1] Registered new searcher Searcher@65928cb0[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3237292 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler I already have the expected version 0 of config
[junit4] 2> 3237292 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler isStale false , resourceloader org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 3237292 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.S.Request [timeConfig_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3237292 INFO (qtp219863730-35850) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Took 310.0ms to set the property overlay to be of version 0 for collection timeConfig
[junit4] 2> 3237292 INFO (qtp219863730-35850) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrCore [timeConfig_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@50c846dd
[junit4] 2> 3237292 INFO (qtp219863730-35850) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.timeConfig.shard1.replica_n1, tag=50c846dd
[junit4] 2> 3237292 INFO (qtp219863730-35850) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@70daae04: rootName = solr_56558, domain = solr.core.timeConfig.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.timeConfig.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@456f507d
[junit4] 2> 3237300 INFO (qtp219863730-35850) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.timeConfig.shard1.leader, tag=50c846dd
[junit4] 2> 3237302 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.ZkController Persisted config data to node /configs/timeConfig/params.json
[junit4] 2> 3237303 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 1 replicas to set the property params to be of version 1 for collection timeConfig
[junit4] 2> 3237303 INFO (Thread-6521) [ ] o.a.s.c.SolrCore config update listener called for core timeConfig_shard1_replica_n1
[junit4] 2> 3237304 INFO (solrHandlerExecutor-14319-thread-1-processing-n:127.0.0.1:56558_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 3237304 INFO (Thread-6521) [ ] o.a.s.c.RequestParams conf resource params.json loaded . version : 1
[junit4] 2> 3237304 INFO (Thread-6521) [ ] o.a.s.c.RequestParams request params refreshed to version 1
[junit4] 2> 3237404 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler I already have the expected version 1 of params
[junit4] 2> 3237404 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler isStale false , resourceloader org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 3237404 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.S.Request [timeConfig_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={params=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3237404 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Took 101.0ms to set the property params to be of version 1 for collection timeConfig
[junit4] 2> 3237408 INFO (qtp219863730-35850) [n:127.0.0.1:56558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=timeConfig&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3237412 INFO (OverseerThreadFactory-14304-thread-3) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true, asyncId=null
[junit4] 2> 3237413 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.timeConfig.shard1.replica_n1, tag=null
[junit4] 2> 3237414 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6d8a0977: rootName = solr_56558, domain = solr.core.timeConfig.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.timeConfig.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@456f507d
[junit4] 2> 3237426 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr ] o.a.s.c.SolrCore [timeConfig_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@4eec4ea8
[junit4] 2> 3237426 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.timeConfig.shard1.replica_n1, tag=4eec4ea8
[junit4] 2> 3237426 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.timeConfig.shard1.leader, tag=4eec4ea8
[junit4] 2> 3237430 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/timeConfig/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 3237431 INFO (qtp219863730-35847) [n:127.0.0.1:56558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=timeConfig_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=18
[junit4] 2> 3238168 INFO (qtp219863730-35850) [n:127.0.0.1:56558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=timeConfig&action=DELETE&wt=javabin&version=2} status=0 QTime=759
[junit4] 2> 3238168 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :list with params action=LIST&wt=javabin&version=2
[junit4] 2> 3238169 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/configs params={action=LIST&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 3238169 INFO (qtp219863730-35850) [n:127.0.0.1:56558_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params property.routedAliasName=myalias&replicationFactor=2&collection.configName=timeConfig&maxShardsPerNode=2&name=myalias_2017-10-23&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 3238172 INFO (OverseerThreadFactory-14304-thread-4) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection myalias_2017-10-23
[junit4] 2> 3238173 INFO (OverseerCollectionConfigSetProcessor-72085559722639366-127.0.0.1:56559_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 3238288 INFO (OverseerStateUpdate-72085559722639366-127.0.0.1:56559_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"myalias_2017-10-23",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"myalias_2017-10-23_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:56558/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3238296 INFO (OverseerStateUpdate-72085559722639366-127.0.0.1:56559_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"myalias_2017-10-23",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"myalias_2017-10-23_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:56559/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3238300 INFO (OverseerStateUpdate-72085559722639366-127.0.0.1:56559_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"myalias_2017-10-23",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"myalias_2017-10-23_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:56558/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3238304 INFO (OverseerStateUpdate-72085559722639366-127.0.0.1:56559_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"myalias_2017-10-23",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"myalias_2017-10-23_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:56559/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 3238516 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=timeConfig&newCollection=true&collection=myalias_2017-10-23&version=2&replicaType=NRT&property.routedAliasName=myalias&coreNodeName=core_node3&name=myalias_2017-10-23_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin
[junit4] 2> 3238517 INFO (qtp219863730-35852) [n:127.0.0.1:56558_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=timeConfig&newCollection=true&collection=myalias_2017-10-23&version=2&replicaType=NRT&property.routedAliasName=myalias&coreNodeName=core_node7&name=myalias_2017-10-23_shard2_replica_n4&action=CREATE&numShards=2&shard=shard2&wt=javabin
[junit4] 2> 3238519 INFO (qtp2066133773-35855) [n:127.0.0.1:56559_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=timeConfig&newCollection=true&collection=myalias_2017-10-23&version=2&replicaType=NRT&property.routedAliasName=myalias&coreNodeName=core_node5&name=myalias_2017-10-23_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin
[junit4] 2> 3238519 INFO (qtp2066133773-35861) [n:127.0.0.1:56559_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=timeConfig&newCollection=true&collection=myalias_2017-10-23&version=2&replicaType=NRT&property.routedAliasName=myalias&coreNodeName=core_node8&name=myalias_2017-10-23_shard2_replica_n6&action=CREATE&numShards=2&shard=shard2&wt=javabin
[junit4] 2> 3238635 INFO (zkCallback-10764-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/myalias_2017-10-23/state.json] for collection [myalias_2017-10-23] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3238635 INFO (zkCallback-10766-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/myalias_2017-10-23/state.json] for collection [myalias_2017-10-23] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3238635 INFO (zkCallback-10766-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/myalias_2017-10-23/state.json] for collection [myalias_2017-10-23] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3238635 INFO (zkCallback-10764-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/myalias_2017-10-23/state.json] for collection [myalias_2017-10-23] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3238635 INFO (zkCallback-10766-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/myalias_2017-10-23/state.json] for collection [myalias_2017-10-23] has occurred - updating... (live nodes size: [2])
[junit4] 2> 3239547 INFO (qtp2066133773-35855) [n:127.0.0.1:56559_solr c:myalias_2017-10-23 s:shard1 r:core_node5 x:myalias_2017-10-23_shard1_replica_n2] o.a.s.c.RequestParams conf resource params.json loaded . version : 1
[junit4] 2> 3239547 INFO (qtp2066133773-35855) [n:127.0.0.1:56559_solr c:myalias_2017-10-23 s:shard1 r:core_node5 x:myalias_2017-10-23_shard1_replica_n2] o.a.s.c.RequestParams request params refreshed to version 1
[junit4] 2> 3239548 WARN (qtp2066133773-35855) [n:127.0.0.1:56559_solr c:myalias_2017-10-23 s:shard1 r:core_node5 x:myalias_2017-10-23_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../contrib/extraction/lib
[junit4] 2> 3239548 WARN (qtp2066133773-35855) [n:127.0.0.1:56559_solr c:myalias_2017-10-23 s:shard1 r:core_node5 x:myalias_2017-10-23_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../dist
[junit4] 2> 3239548 WARN (qtp2066133773-35855) [n:127.0.0.1:56559_solr c:myalias_2017-10-23 s:shard1 r:core_node5 x:myalias_2017-10-23_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../contrib/clustering/lib
[junit4] 2> 3239548 WARN (qtp2066133773-35855) [n:127.0.0.1:56559_solr c:myalias_2017-10-23 s:shard1 r:core_node5 x:myalias_2017-10-23_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../dist
[junit4] 2> 3239548 WARN (qtp2066133773-35855) [n:127.0.0.1:56559_solr c:myalias_2017-10-23 s:shard1 r:core_node5 x:myalias_2017-10-23_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../contrib/langid/lib
[junit4] 2> 3239548 WARN (qtp2066133773-35855) [n:127.0.0.1:56559_solr c:myalias_2017-10-23 s:shard1 r:core_node5 x:myalias_2017-10-23_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../dist
[junit4] 2> 3239549 WARN (qtp2066133773-35855) [n:127.0.0.1:56559_solr c:myalias_2017-10-23 s:shard1 r:core_node5 x:myalias_2017-10-23_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../contrib/velocity/lib
[junit4] 2> 3239549 WARN (qtp2066133773-35855) [n:127.0.0.1:56559_solr c:myalias_2017-10-23 s:shard1 r:core_node5 x:myalias_2017-10-23_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node1/myalias_2017-10-23_shard1_replica_n2/../../../../dist
[junit4] 2> 3239572 INFO (qtp2066133773-35855) [n:127.0.0.1:56559_solr c:myalias_2017-10-23 s:shard1 r:core_node5 x:myalias_2017-10-23_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 3239580 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:myalias_2017-10-23 s:shard1 r:core_node3 x:myalias_2017-10-23_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 1
[junit4] 2> 3239580 INFO (qtp219863730-35851) [n:127.0.0.1:56558_solr c:myalias_2017-10-23 s:shard1 r:core_node3 x:myalias_2017-10-23_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 1
[junit4] 2> 3239581 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:myalias_2017-10-23 s:shard1 r:core_node3 x:myalias_2017-10-23_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 3239581 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:myalias_2017-10-23 s:shard1 r:core_node3 x:myalias_2017-10-23_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../dist
[junit4] 2> 3239581 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:myalias_2017-10-23 s:shard1 r:core_node3 x:myalias_2017-10-23_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 3239581 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:myalias_2017-10-23 s:shard1 r:core_node3 x:myalias_2017-10-23_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../dist
[junit4] 2> 3239581 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:myalias_2017-10-23 s:shard1 r:core_node3 x:myalias_2017-10-23_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 3239581 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:myalias_2017-10-23 s:shard1 r:core_node3 x:myalias_2017-10-23_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../dist
[junit4] 2> 3239581 WARN (qtp219863730-35851) [n:127.0.0.1:56558_solr c:myalias_2017-10-23 s:shard1 r:core_node3 x:myalias_2017-10-23_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339277C0-001/tempDir-001/node2/myalias_2017-10-23_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_A17AE08A339
[...truncated too long message...]
ons, CloudMLTQParserTest, TestDistribIDF, HdfsWriteToMultipleCollectionsTest, DocExpirationUpdateProcessorFactoryTest, BaseCdcrDistributedZkTest, AnalyticsMergeStrategyTest, SmileWriterTest, TestExecutePlanAction, TestNumericRangeQuery64, TestManagedSynonymFilterFactory, BasicAuthIntegrationTest, TestInitParams, StatsReloadRaceTest, TestFaceting, LargeFieldTest, TestCharFilters, TestFastOutputStream, SampleTest, TestRTimerTree, TriggerCooldownIntegrationTest, TestStressCloudBlindAtomicUpdates, ZkFailoverTest, QueryElevationComponentTest, TestBulkSchemaConcurrent, HttpTriggerListenerTest, AsyncCallRequestStatusResponseTest, TestHdfsCloudBackupRestore, CollectionTooManyReplicasTest, MetricTriggerTest, CollectionPropsTest, TestClusterStateMutator, MoveReplicaTest, TestCollationField, ExplicitHLLTest, SuggesterTSTTest, TestNestedDocsSort, TestLFUCache, BufferStoreTest, SharedFSAutoReplicaFailoverTest, TestCloudSchemaless, StatelessScriptUpdateProcessorFactoryTest, TestHalfAndHalfDocValues, TestHashQParserPlugin, TestPKIAuthenticationPlugin, TriggerSetPropertiesIntegrationTest, TestSlowCompositeReaderWrapper, MoveReplicaHDFSFailoverTest, TestSimDistributedQueue, HdfsChaosMonkeyNothingIsSafeTest, MultiTermTest, TestReload, ExitableDirectoryReaderTest, ReplaceNodeTest, TestChildDocTransformer, TestLazyCores, AutoScalingHandlerTest, TestSearcherReuse, DistributedFacetExistsSmallTest, RestartWhileUpdatingTest, TestBinaryField, CdcrBootstrapTest, TestZkAclsWithHadoopAuth, TestPayloadCheckQParserPlugin, WordBreakSolrSpellCheckerTest, TestCloudPseudoReturnFields, ConvertedLegacyTest, FileUtilsTest, TestSubQueryTransformerCrossCore, TestFieldTypeResource, LeaderElectionIntegrationTest, HdfsNNFailoverTest, CurrencyRangeFacetCloudTest, CreateCollectionCleanupTest, TestDirectoryFactory, BlockJoinFacetRandomTest, TestPullReplica, CdcrReplicationHandlerTest, TestSha256AuthenticationProvider, InfixSuggestersTest, RegexBytesRefFilterTest, CacheHeaderTest, TestFieldCacheSort, TestPartialUpdateDeduplication, SpellingQueryConverterTest, EnumFieldTest, TestReplicationHandlerBackup, HighlighterTest, TestRangeQuery, TestMaxTokenLenTokenizer, TestRestoreCore, RecoveryZkTest, TestSchemalessBufferedUpdates, CustomCollectionTest, TestQueryUtils, TestFoldingMultitermQuery, TestGroupingSearch, SuggesterFSTTest, CopyFieldTest, OutputWriterTest, BJQParserTest, MetricsConfigTest, OverseerModifyCollectionTest, ActionThrottleTest, TestMergePolicyConfig, TestCustomSort, TermVectorComponentDistributedTest, NumericFieldsTest, PreAnalyzedFieldManagedSchemaCloudTest, TestCursorMarkWithoutUniqueKey, BlockCacheTest, TestLegacyFieldCache, TestSolrConfigHandlerConcurrent, TestScoreJoinQPScore, TestTrackingShardHandlerFactory, TestSQLHandlerNonCloud, CollectionsAPISolrJTest, RestoreTriggerStateTest, SolrCloudExampleTest, SolrIndexConfigTest, TestOmitPositions, TestStressLiveNodes, AutoAddReplicasIntegrationTest, CdcrVersionReplicationTest, SparseHLLTest, SSLMigrationTest, TestCoreAdminApis, CleanupOldIndexTest, ChaosMonkeySafeLeaderWithPullReplicasTest, NodeLostTriggerTest, TestCopyFieldCollectionResource, DistributedQueryElevationComponentTest, TestManagedSchemaAPI, TestConfigOverlay, SolrMetricManagerTest, SolrJmxReporterTest, TestManagedResource, TestSchemaResource, ResourceLoaderTest, TestPhraseSuggestions, SolrCloudReportersTest, HdfsLockFactoryTest, TestRealTimeGet, TestCollectionAPI, NodeMutatorTest, TestZkChroot, ZkCLITest, TestHttpShardHandlerFactory, AutoscalingHistoryHandlerTest, TestFieldCacheVsDocValues, CachingDirectoryFactoryTest, DateFieldTest, PingRequestHandlerTest, CollectionsAPIDistributedZkTest, DirectSolrConnectionTest, TestRemoteStreaming, SpellCheckCollatorWithCollapseTest, SOLR749Test, SystemLogListenerTest, AlternateDirectoryTest, TestUniqueKeyFieldResource, TestEmbeddedSolrServerConstructors, SolrRequestParserTest, PathHierarchyTokenizerFactoryTest, CdcrUpdateLogTest, TestIndexSearcher, TestTestInjection, ResponseBuilderTest, SecurityConfHandlerTest, FieldMutatingUpdateProcessorTest, ChangedSchemaMergeTest, TestDocBasedVersionConstraints, VersionInfoTest, HdfsTlogReplayBufferedWhileIndexingTest, TestSuggestSpellingConverter, TestCloudDeleteByQuery, DistributedVersionInfoTest, OpenCloseCoreStressTest, HLLSerializationTest, TestFreeTextSuggestions, TestCoreContainer, TestCustomDocTransformer, TestSkipOverseerOperations, TestShortCircuitedRequests, DeleteNodeTest, TestUtilizeNode, ResponseHeaderTest, TestQueryWrapperFilter, BasicDistributedZkTest, SyncSliceTest, TestReplicationHandler, ShardRoutingCustomTest, TestRecovery, DistributedSpellCheckComponentTest, DistributedTermsComponentTest, PeerSyncTest, SoftAutoCommitTest, TestSolrDeletionPolicy2, TestSearchPerf, TestConfig, ExternalFileFieldSortTest, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, SynonymTokenizerTest, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, ScriptEngineTest, OpenExchangeRatesOrgProviderTest, PluginInfoTest, TestFastLRUCache, DateMathParserTest, PreAnalyzedFieldTest, TestSystemIdResolver, PrimUtilsTest, DOMUtilTest, RAMDirectoryFactoryTest, ClusterStateTest, TestSolrJ, TestLRUCache, TestUtils, TestDocumentBuilder, ZkNodePropsTest, SystemInfoHandlerTest, CircularListTest, DistributedMLTComponentTest, TestRTGBase, CursorPagingTest, DistributedIntervalFacetingTest, TestCrossCoreJoin, TestSimpleTrackingShardHandler, TestJettySolrRunner, AddReplicaTest, ConcurrentCreateRoutedAliasTest, DeleteInactiveReplicaTest, DeleteShardTest, DeleteStatusTest, DistribCursorPagingTest, DistributedQueueTest, ForceLeaderTest, HttpPartitionTest, LIRRollingUpdatesTest, RemoteQueryErrorTest, RollingRestartTest, TestCloudPivotFacet, TestClusterProperties, TestConfigSetsAPI, TestConfigSetsAPIExclusivity, TestDeleteCollectionOnDownNodes, TestDistributedMap, TestExclusionRuleCollectionAccess, TestLeaderElectionZkExpiry, TestLockTree, TestMiniSolrCloudClusterSSL, TestRandomFlRTGCloud, TestSSLRandomization, TestSizeLimitedDistributedMap, TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, TestTlogReplica, CollectionsAPIAsyncDistributedZkTest, ShardSplitTest, ExecutePlanActionTest, NodeAddedTriggerTest, TestPolicyCloud, TestComputePlanAction, TestLargeCluster, TestNodeLostTrigger, TestPolicyCloud, CdcrReplicationDistributedZkTest, CdcrRequestHandlerTest, HdfsBasicDistributedZk2Test, HdfsRecoverLeaseTest, HdfsRecoveryZkTest, HdfsRestartWhileUpdatingTest, HdfsSyncSliceTest, HdfsUnloadDistributedZkTest, ZkStateReaderTest, RuleEngineTest, BlobRepositoryCloudTest, TestCorePropertiesReload, TestImplicitCoreProperties, TestNRTOpen, TestReloadAndDeleteDocs, TestShardHandlerFactory, TestSimpleTextCodec, TestSolrConfigHandler, TestSolrXml, TestSolrCloudSnapshots, TestCoreBackup, TestSolrConfigHandlerCloud, CoreAdminOperationTest, CoreAdminRequestStatusTest, SegmentsInfoRequestHandlerTest, TestConfigsApi, DistributedDebugComponentTest, DistributedFacetPivotLargeTest, DistributedFacetPivotSmallTest, DistributedQueryComponentCustomSortTest, DistributedSuggestComponentTest, SuggestComponentContextFilterQueryTest, TestDistributedStatsComponentCardinality, TestLegacyNumericUtils, TestNumericRangeQuery32, SolrMetricsIntegrationTest, SolrGraphiteReporterTest, SubstringBytesRefFilterTest, TestStreamBody, TestMacroExpander, TestMacros, TestExportWriter, TestPushWriter, TestRawTransformer, TestRetrieveFieldsOptimizer, TestRestManager, TestDynamicFieldResource, TestFieldCollectionResource, TestFieldResource, TestFieldTypeCollectionResource, TestSchemaVersionResource, TestSerializedLuceneMatchVersion, BadCopyFieldTest, DateRangeFieldTest, SchemaApiFailureTest, TestCloudManagedSchema, TestUseDocValuesAsStored, CursorMarkTest, RankQueryTest, TestAddFieldRealTimeGet, TestComplexPhraseLeadingWildcard, TestComplexPhraseQParserPlugin, TestFilteredDocIdSet, TestInitQParser, TestLegacyNumericRangeQueryBuilder, TestMissingGroups, TestRandomCollapseQParserPlugin, TestRecoveryHdfs, TestSimpleQParserPlugin, TestSmileRequest, TestSolrCoreParser, TestStandardQParsers, TestTrieFacet, TestXmlQParser, TestXmlQParserPlugin, DistributedFacetSimpleRefinementLongTailTest, TestJsonFacetRefinement, TestJsonFacetsWithNestedObjects, TestMinMaxOnMultiValuedField, TestOrdValues, TestSortByMinMaxFunction, BlockJoinFacetDistribTest, TestDefaultStatsCache, TestExactStatsCache, PKIAuthenticationIntegrationTest, TestUninvertingReader, AddBlockUpdateTest, SolrIndexMetricsTest, TestHdfsUpdateLog, TestInPlaceUpdatesStandalone, TimeRoutedAliasUpdateProcessorTest]
[junit4] Completed [786/794 (1!)] on J0 in 15.14s, 2 tests, 1 failure <<< FAILURES!
[...truncated 42775 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/564/consoleText
[repro] Revision: 2ccea7b4754f10f8474e72ff22f77f6088fc9282
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=TimeRoutedAliasUpdateProcessorTest -Dtests.method=test -Dtests.seed=A17AE08A339277C0 -Dtests.slow=true -Dtests.locale=teo-UG -Dtests.timezone=America/Argentina/Jujuy -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TimeRoutedAliasUpdateProcessorTest
[repro] ant compile-test
[...truncated 3341 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TimeRoutedAliasUpdateProcessorTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=A17AE08A339277C0 -Dtests.slow=true -Dtests.locale=teo-UG -Dtests.timezone=America/Argentina/Jujuy -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 78 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest
[repro] Exiting with code 0
[...truncated 70 lines...]
[JENKINS] Lucene-Solr-7.x-MacOSX (64bit/jdk1.8.0) - Build # 565 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/565/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.cloud.TestLockTree.testLocks
Error Message:
expected:<2> but was:<3>
Stack Trace:
java.lang.AssertionError: expected:<2> but was:<3>
at __randomizedtesting.SeedInfo.seed([2E23771CAD798DE8:57E90DE5A5A96603]: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.cloud.TestLockTree.testLocks(TestLockTree.java:99)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.ZkFailoverTest.testRestartZkWhenClusterDown
Error Message:
Collection not found: coll1
Stack Trace:
org.apache.solr.common.SolrException: Collection not found: coll1
at __randomizedtesting.SeedInfo.seed([2E23771CAD798DE8:40B4F450D49E2B6B]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:853)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
at org.apache.solr.cloud.ZkFailoverTest.testRestartZkWhenClusterDown(ZkFailoverTest.java:50)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14072 lines...]
[junit4] Suite: org.apache.solr.cloud.TestLockTree
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestLockTree_2E23771CAD798DE8-001/init-core-data-001
[junit4] 2> 2417207 WARN (SUITE-TestLockTree-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 2417207 INFO (SUITE-TestLockTree-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2417212 INFO (SUITE-TestLockTree-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 2417225 INFO (SUITE-TestLockTree-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2417232 INFO (TEST-TestLockTree.testLocks-seed#[2E23771CAD798DE8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLocks
[junit4] 2> 2417265 INFO (TEST-TestLockTree.testLocks-seed#[2E23771CAD798DE8]) [ ] o.a.s.c.TestLockTree counter : 0 , expected : [coll1/shard1/core_node2, coll2/shard2], actual : [coll1/shard1/core_node2, coll1, coll2/shard2]
[junit4] 2> 2417266 INFO (TEST-TestLockTree.testLocks-seed#[2E23771CAD798DE8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLocks
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLockTree -Dtests.method=testLocks -Dtests.seed=2E23771CAD798DE8 -Dtests.slow=true -Dtests.locale=zh-CN -Dtests.timezone=ACT -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.04s J0 | TestLockTree.testLocks <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<2> but was:<3>
[junit4] > at __randomizedtesting.SeedInfo.seed([2E23771CAD798DE8:57E90DE5A5A96603]:0)
[junit4] > at org.apache.solr.cloud.TestLockTree.testLocks(TestLockTree.java:99)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestLockTree_2E23771CAD798DE8-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=292, maxMBSortInHeap=6.400668691057178, sim=RandomSimilarity(queryNorm=true): {}, locale=zh-CN, timezone=ACT
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=107038864,total=464982016
[junit4] 2> NOTE: All tests run in this JVM: [SecurityConfHandlerTest, TestFieldCacheSort, ClassificationUpdateProcessorTest, CreateCollectionCleanupTest, TestTrie, TestCustomSort, TestSolrCoreSnapshots, TestNamedUpdateProcessors, OverriddenZkACLAndCredentialsProvidersTest, MoveReplicaTest, SolrInfoBeanTest, SystemLogListenerTest, AlternateDirectoryTest, TestImplicitCoreProperties, HdfsSyncSliceTest, TestZkChroot, TestNumericTerms64, TestTestInjection, TestTolerantUpdateProcessorCloud, SolrTestCaseJ4Test, ChangedSchemaMergeTest, SolrIndexConfigTest, TestPartialUpdateDeduplication, TestCloudSearcherWarming, DistributedFacetPivotLongTailTest, TestBinaryField, AssignTest, HdfsRecoveryZkTest, HdfsWriteToMultipleCollectionsTest, TestSolrDeletionPolicy1, XmlUpdateRequestHandlerTest, TestOnReconnectListenerSupport, SSLMigrationTest, LukeRequestHandlerTest, TestPullReplica, TestCloudJSONFacetJoinDomain, DeleteNodeTest, ResourceLoaderTest, TestExecutePlanAction, TestCoreContainer, ConfigSetsAPITest, PKIAuthenticationIntegrationTest, TestHighlightDedupGrouping, TestFoldingMultitermQuery, TestRTimerTree, CacheHeaderTest, TestDistributedMap, TestFaceting, BigEndianAscendingWordSerializerTest, TestQueryTypes, TestEmbeddedSolrServerConstructors, CachingDirectoryFactoryTest, DistributedVersionInfoTest, TestJettySolrRunner, BasicDistributedZkTest, TestSolrXml, HttpPartitionTest, TestRetrieveFieldsOptimizer, TestCustomDocTransformer, TestDirectoryFactory, RequiredFieldsTest, TestPointFields, TestMergePolicyConfig, NodeAddedTriggerTest, TestDistribIDF, TestPayloadCheckQParserPlugin, HighlighterTest, SmileWriterTest, DistributedQueryComponentCustomSortTest, StatsComponentTest, BlockJoinFacetRandomTest, DocumentBuilderTest, ResponseBuilderTest, TestCoreAdminApis, TestReloadAndDeleteDocs, TestLegacyFieldCache, TestUniqueKeyFieldResource, TestInPlaceUpdatesStandalone, PingRequestHandlerTest, ZkNodePropsTest, TestMissingGroups, AsyncCallRequestStatusResponseTest, PreAnalyzedFieldManagedSchemaCloudTest, MetricsHandlerTest, AliasIntegrationTest, TriggerSetPropertiesIntegrationTest, BufferStoreTest, TestAddFieldRealTimeGet, BlockCacheTest, TestSolrCoreParser, LIRRollingUpdatesTest, TestConfigOverlay, SolrIndexSplitterTest, SolrMetricManagerTest, HdfsNNFailoverTest, DirectUpdateHandlerOptimizeTest, DateFieldTest, TestOmitPositions, DateRangeFieldTest, ActionThrottleTest, TriLevelCompositeIdRoutingTest, ExitableDirectoryReaderTest, CdcrUpdateLogTest, TestManagedSynonymFilterFactory, TestPhraseSuggestions, CollectionsAPIDistributedZkTest, CoreAdminOperationTest, VersionInfoTest, TestSchemaResource, TermsComponentTest, DocExpirationUpdateProcessorFactoryTest, AddBlockUpdateTest, OpenCloseCoreStressTest, CoreSorterTest, TestSmileRequest, TestConfigSetsAPI, TestDynamicFieldResource, TestPostingsSolrHighlighter, SearchHandlerTest, RecoveryZkTest, TestFastOutputStream, TestOrdValues, TestChildDocTransformer, HdfsChaosMonkeyNothingIsSafeTest, TestBulkSchemaConcurrent, SolrPluginUtilsTest, TestFunctionQuery, TestCSVResponseWriter, TestJsonFacetRefinement, TestFreeTextSuggestions, DirectSolrConnectionTest, TestRestManager, SpatialFilterTest, TestExactStatsCache, CdcrReplicationHandlerTest, IndexSchemaRuntimeFieldTest, TestRemoteStreaming, TestLegacyField, TestUtils, BasicAuthIntegrationTest, TestIBSimilarityFactory, TestCollationField, PolyFieldTest, TestCloudPseudoReturnFields, UniqFieldsUpdateProcessorFactoryTest, QueryEqualityTest, TestSchemaVersionResource, SynonymTokenizerTest, TestHalfAndHalfDocValues, TestShortCircuitedRequests, TestHashQParserPlugin, TestDocBasedVersionConstraints, ShufflingReplicaListTransformerTest, LeaderVoteWaitTimeoutTest, AnalysisAfterCoreReloadTest, TestSlowCompositeReaderWrapper, TestPerFieldSimilarity, TestReplicationHandlerBackup, MBeansHandlerTest, EnumFieldTest, DistributedFacetPivotSmallTest, RankQueryTest, SignatureUpdateProcessorFactoryTest, DeleteInactiveReplicaTest, TestImpersonationWithHadoopAuth, TestGeoJSONResponseWriter, TestSolrConfigHandlerConcurrent, DistributedSuggestComponentTest, TestIndexSearcher, ShardRoutingCustomTest, AutoscalingHistoryHandlerTest, CopyFieldTest, TestMultiValuedNumericRangeQuery, AtomicUpdateProcessorFactoryTest, CurrencyRangeFacetCloudTest, TestClusterStateMutator, SuggestComponentContextFilterQueryTest, TestLegacyNumericRangeQueryBuilder, TestRecoveryHdfs, HdfsBasicDistributedZk2Test, TestConfigSetsAPIZkFailure, TestTrieFacet, DirectSolrSpellCheckerTest, HttpTriggerListenerTest, OverseerModifyCollectionTest, DistributedMLTComponentTest, TestReplicationHandler, TestNoOpRegenerator, TestExclusionRuleCollectionAccess, WordBreakSolrSpellCheckerTest, TestStandardQParsers, TestJavabinTupleStreamParser, TriggerCooldownIntegrationTest, TestCopyFieldCollectionResource, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, LeaderElectionTest, TestRandomDVFaceting, ZkCLITest, TestRecovery, TestHashPartitioner, TermVectorComponentDistributedTest, TestRealTimeGet, TestStressReorder, TestJoin, TestReload, TestRangeQuery, SimpleFacetsTest, TestSolr4Spatial, SolrCoreTest, TestGroupingSearch, QueryElevationComponentTest, TestSort, TestFiltering, TestBadConfig, DistributedQueryElevationComponentTest, SuggesterFSTTest, SolrRequestParserTest, SuggesterTSTTest, TestStressLucene, SchemaVersionSpecificBehaviorTest, FieldMutatingUpdateProcessorTest, DistanceFunctionTest, TestSurroundQueryParser, TestQueryUtils, DocumentAnalysisRequestHandlerTest, RequestHandlersTest, TermVectorComponentTest, FastVectorHighlighterTest, TestJmxIntegration, QueryParsingTest, BinaryUpdateRequestHandlerTest, TestLFUCache, TestDFRSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestFastWriter, OpenExchangeRatesOrgProviderTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, ConcurrentCreateRoutedAliasTest, ConnectionManagerTest, DeleteLastCustomShardedReplicaTest, DeleteShardTest, DistribCursorPagingTest, DistribJoinFromCollectionTest, DistributedQueueTest, DocValuesNotIndexedTest, ForceLeaderTest, LIROnShardRestartTest, LeaderFailoverAfterPartitionTest, LeaderFailureAfterFreshStartTest, MoveReplicaHDFSTest, OverseerRolesTest, OverseerTaskQueueTest, ReplicationFactorTest, RestartWhileUpdatingTest, RollingRestartTest, SharedFSAutoReplicaFailoverTest, SolrCloudExampleTest, TestClusterProperties, TestDeleteCollectionOnDownNodes, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestLockTree]
[junit4] Completed [567/794 (1!)] on J0 in 0.13s, 1 test, 1 failure <<< FAILURES!
[...truncated 62 lines...]
[junit4] Suite: org.apache.solr.cloud.ZkFailoverTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/init-core-data-001
[junit4] 2> 2638232 WARN (SUITE-ZkFailoverTest-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 2638232 INFO (SUITE-ZkFailoverTest-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2638243 INFO (SUITE-ZkFailoverTest-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 2638246 INFO (SUITE-ZkFailoverTest-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2638247 INFO (SUITE-ZkFailoverTest-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001
[junit4] 2> 2638247 INFO (SUITE-ZkFailoverTest-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2638247 INFO (Thread-4785) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2638247 INFO (Thread-4785) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2638252 ERROR (Thread-4785) [ ] 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> 2638354 INFO (SUITE-ZkFailoverTest-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:62536
[junit4] 2> 2638371 INFO (zkConnectionManagerCallback-8421-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2638395 INFO (jetty-launcher-8418-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T12:27:37-09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2638395 INFO (jetty-launcher-8418-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T12:27:37-09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 2638397 INFO (jetty-launcher-8418-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2638397 INFO (jetty-launcher-8418-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2638397 INFO (jetty-launcher-8418-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 2638397 INFO (jetty-launcher-8418-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4502afa5{/solr,null,AVAILABLE}
[junit4] 2> 2638406 INFO (jetty-launcher-8418-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2638406 INFO (jetty-launcher-8418-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2638406 INFO (jetty-launcher-8418-thread-1) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 2638406 INFO (jetty-launcher-8418-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d4f6c21{/solr,null,AVAILABLE}
[junit4] 2> 2638410 INFO (jetty-launcher-8418-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6ab10c58{SSL,[ssl, http/1.1]}{127.0.0.1:62539}
[junit4] 2> 2638410 INFO (jetty-launcher-8418-thread-1) [ ] o.e.j.s.Server Started @2638505ms
[junit4] 2> 2638410 INFO (jetty-launcher-8418-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62539}
[junit4] 2> 2638410 ERROR (jetty-launcher-8418-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2638410 INFO (jetty-launcher-8418-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2638411 INFO (jetty-launcher-8418-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@56f11574{SSL,[ssl, http/1.1]}{127.0.0.1:62538}
[junit4] 2> 2638411 INFO (jetty-launcher-8418-thread-2) [ ] o.e.j.s.Server Started @2638506ms
[junit4] 2> 2638411 INFO (jetty-launcher-8418-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62538}
[junit4] 2> 2638411 ERROR (jetty-launcher-8418-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2638411 INFO (jetty-launcher-8418-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2638411 INFO (jetty-launcher-8418-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.4.0
[junit4] 2> 2638411 INFO (jetty-launcher-8418-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2638411 INFO (jetty-launcher-8418-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2638411 INFO (jetty-launcher-8418-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-08T14:49:21.753Z
[junit4] 2> 2638412 INFO (jetty-launcher-8418-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.4.0
[junit4] 2> 2638412 INFO (jetty-launcher-8418-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2638412 INFO (jetty-launcher-8418-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2638412 INFO (jetty-launcher-8418-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-08T14:49:21.754Z
[junit4] 2> 2638415 INFO (zkConnectionManagerCallback-8423-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2638415 INFO (zkConnectionManagerCallback-8425-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2638416 INFO (jetty-launcher-8418-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2638416 INFO (jetty-launcher-8418-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2638458 INFO (jetty-launcher-8418-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62536/solr
[junit4] 2> 2638458 INFO (jetty-launcher-8418-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62536/solr
[junit4] 2> 2638461 INFO (zkConnectionManagerCallback-8431-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2638461 INFO (zkConnectionManagerCallback-8433-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2638465 INFO (zkConnectionManagerCallback-8435-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2638465 INFO (zkConnectionManagerCallback-8437-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2638750 INFO (jetty-launcher-8418-thread-2) [n:127.0.0.1:62538_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2638750 INFO (jetty-launcher-8418-thread-1) [n:127.0.0.1:62539_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2638751 INFO (jetty-launcher-8418-thread-2) [n:127.0.0.1:62538_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62538_solr
[junit4] 2> 2638752 INFO (jetty-launcher-8418-thread-1) [n:127.0.0.1:62539_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62539_solr
[junit4] 2> 2638753 INFO (jetty-launcher-8418-thread-2) [n:127.0.0.1:62538_solr ] o.a.s.c.Overseer Overseer (id=72088043796037638-127.0.0.1:62538_solr-n_0000000000) starting
[junit4] 2> 2638756 INFO (zkCallback-8434-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2638759 INFO (zkCallback-8436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2638770 INFO (zkConnectionManagerCallback-8446-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2638772 INFO (jetty-launcher-8418-thread-2) [n:127.0.0.1:62538_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2638773 INFO (jetty-launcher-8418-thread-2) [n:127.0.0.1:62538_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62536/solr ready
[junit4] 2> 2638779 INFO (jetty-launcher-8418-thread-2) [n:127.0.0.1:62538_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2638779 INFO (jetty-launcher-8418-thread-2) [n:127.0.0.1:62538_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62538_solr
[junit4] 2> 2638783 INFO (zkCallback-8445-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2638785 INFO (zkCallback-8436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2638787 INFO (zkCallback-8434-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2639928 INFO (jetty-launcher-8418-thread-1) [n:127.0.0.1:62539_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62539.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a378c63
[junit4] 2> 2639937 INFO (jetty-launcher-8418-thread-1) [n:127.0.0.1:62539_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62539.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a378c63
[junit4] 2> 2639938 INFO (jetty-launcher-8418-thread-1) [n:127.0.0.1:62539_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62539.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a378c63
[junit4] 2> 2639940 INFO (jetty-launcher-8418-thread-1) [n:127.0.0.1:62539_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/.
[junit4] 2> 2640004 INFO (zkConnectionManagerCallback-8451-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2640011 INFO (jetty-launcher-8418-thread-1) [n:127.0.0.1:62539_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2640013 INFO (jetty-launcher-8418-thread-1) [n:127.0.0.1:62539_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62536/solr ready
[junit4] 2> 2640149 INFO (jetty-launcher-8418-thread-2) [n:127.0.0.1:62538_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62538.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a378c63
[junit4] 2> 2640163 INFO (jetty-launcher-8418-thread-2) [n:127.0.0.1:62538_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62538.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a378c63
[junit4] 2> 2640163 INFO (jetty-launcher-8418-thread-2) [n:127.0.0.1:62538_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62538.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4a378c63
[junit4] 2> 2640165 INFO (jetty-launcher-8418-thread-2) [n:127.0.0.1:62538_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/.
[junit4] 2> 2640208 INFO (zkConnectionManagerCallback-8453-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2640216 INFO (zkConnectionManagerCallback-8458-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2640217 INFO (SUITE-ZkFailoverTest-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2640219 INFO (SUITE-ZkFailoverTest-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62536/solr ready
[junit4] 2> 2640410 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[2E23771CAD798DE8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRestartZkWhenClusterDown
[junit4] 2> 2640465 INFO (qtp1450443904-27656) [n:127.0.0.1:62539_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=1&name=coll1&nrtReplicas=1&action=CREATE&numShards=2&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2640473 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection coll1
[junit4] 2> 2640475 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/coll1.AUTOCREATED/managed-schema
[junit4] 2> 2640478 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/coll1.AUTOCREATED/protwords.txt
[junit4] 2> 2640482 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/coll1.AUTOCREATED/solrconfig.xml
[junit4] 2> 2640485 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/coll1.AUTOCREATED/synonyms.txt
[junit4] 2> 2640488 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/coll1.AUTOCREATED/stopwords.txt
[junit4] 2> 2640492 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/coll1.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 2640496 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/coll1.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 2640499 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 2640504 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/coll1.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 2640508 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/coll1.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 2640512 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/coll1.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 2640515 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 2640519 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 2640523 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 2640526 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/coll1.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 2640530 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 2640533 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/coll1.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 2640543 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 2640546 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/coll1.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 2640549 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 2640553 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/coll1.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 2640557 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/coll1.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 2640561 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/coll1.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 2640564 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/coll1.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 2640568 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/coll1.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 2640574 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/coll1.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 2640578 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/coll1.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 2640581 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 2640585 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/coll1.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 2640589 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 2640593 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/coll1.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 2640596 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/coll1.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 2640618 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/coll1.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 2640627 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/coll1.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 2640634 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/coll1.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 2640637 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 2640640 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/coll1.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 2640658 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 2640661 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/coll1.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 2640665 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 2640668 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/coll1.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 2640671 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/coll1.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 2640674 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/coll1.AUTOCREATED/params.json
[junit4] 2> 2640803 INFO (OverseerStateUpdate-72088043796037638-127.0.0.1:62538_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"coll1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"coll1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:62539/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2640807 INFO (OverseerStateUpdate-72088043796037638-127.0.0.1:62538_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"coll1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"coll1_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:62538/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2641091 INFO (qtp1450443904-27654) [n:127.0.0.1:62539_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=coll1.AUTOCREATED&newCollection=true&name=coll1_shard1_replica_n1&action=CREATE&numShards=2&collection=coll1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2641092 INFO (qtp1450443904-27654) [n:127.0.0.1:62539_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2641116 INFO (qtp269697565-27648) [n:127.0.0.1:62538_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=coll1.AUTOCREATED&newCollection=true&name=coll1_shard2_replica_n2&action=CREATE&numShards=2&collection=coll1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2641241 INFO (zkCallback-8434-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2641241 INFO (zkCallback-8434-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2641242 INFO (zkCallback-8436-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2641242 INFO (zkCallback-8436-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2642130 INFO (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 2642130 INFO (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 2642131 WARN (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 2642131 WARN (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../dist
[junit4] 2> 2642132 WARN (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 2642132 WARN (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../dist
[junit4] 2> 2642161 WARN (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 2642161 WARN (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../dist
[junit4] 2> 2642162 WARN (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 2642162 WARN (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node1/coll1_shard1_replica_n1/../../../../dist
[junit4] 2> 2642140 INFO (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 2642162 INFO (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 2642164 WARN (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../contrib/extraction/lib
[junit4] 2> 2642167 WARN (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../dist
[junit4] 2> 2642168 WARN (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../contrib/clustering/lib
[junit4] 2> 2642168 WARN (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../dist
[junit4] 2> 2642168 WARN (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../contrib/langid/lib
[junit4] 2> 2642169 WARN (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../dist
[junit4] 2> 2642169 WARN (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../contrib/velocity/lib
[junit4] 2> 2642169 WARN (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001/tempDir-001/node2/coll1_shard2_replica_n2/../../../../dist
[junit4] 2> 2642172 INFO (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 2642375 INFO (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
[junit4] 2> 2642419 INFO (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.s.IndexSchema [coll1_shard1_replica_n1] Schema name=default-config
[junit4] 2> 2642425 INFO (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.s.IndexSchema [coll1_shard2_replica_n2] Schema name=default-config
[junit4] 2> 2642800 ERROR (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Error CREATEing SolrCore 'coll1_shard2_replica_n2': Unable to create core [coll1_shard2_replica_n2] Caused by: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:996)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:90)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:356)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:389)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:174)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:736)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:498)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:393)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:339)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:530)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:289)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:149)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Unable to create core [coll1_shard2_replica_n2]
[junit4] 2> at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1068)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:957)
[junit4] 2> ... 42 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Could not load conf for core coll1_shard2_replica_n2: Can't load schema managed-schema: org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:97)
[junit4] 2> at org.apache.solr.core.CoreContainer.getConfigSet(CoreContainer.java:1096)
[junit4] 2> at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1043)
[junit4] 2> ... 43 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Can't load schema managed-schema: org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.schema.IndexSchema.readSchema(IndexSchema.java:582)
[junit4] 2> at org.apache.solr.schema.IndexSchema.<init>(IndexSchema.java:179)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchema.<init>(ManagedIndexSchema.java:105)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:173)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:45)
[junit4] 2> at org.apache.solr.schema.IndexSchemaFactory.buildIndexSchema(IndexSchemaFactory.java:75)
[junit4] 2> at org.apache.solr.core.ConfigSetService.createIndexSchema(ConfigSetService.java:119)
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:92)
[junit4] 2> ... 45 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchema.informResourceLoaderAwareObjectsInChain(ManagedIndexSchema.java:1317)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchema.informResourceLoaderAwareObjectsForFieldType(ManagedIndexSchema.java:1168)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchema.postReadInform(ManagedIndexSchema.java:1153)
[junit4] 2> at org.apache.solr.schema.IndexSchema.readSchema(IndexSchema.java:578)
[junit4] 2> ... 52 more
[junit4] 2> Caused by: org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoader.java:130)
[junit4] 2> at org.apache.lucene.analysis.util.AbstractAnalysisFactory.getLines(AbstractAnalysisFactory.java:254)
[junit4] 2> at org.apache.lucene.analysis.util.AbstractAnalysisFactory.getWordSet(AbstractAnalysisFactory.java:243)
[junit4] 2> at org.apache.lucene.analysis.util.ElisionFilterFactory.inform(ElisionFilterFactory.java:59)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchema.informResourceLoaderAwareObjectsInChain(ManagedIndexSchema.java:1315)
[junit4] 2> ... 55 more
[junit4] 2>
[junit4] 2> 2642801 INFO (qtp269697565-27648) [n:127.0.0.1:62538_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=coll1.AUTOCREATED&newCollection=true&name=coll1_shard2_replica_n2&action=CREATE&numShards=2&collection=coll1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=400 QTime=1684
[junit4] 2> 2642801 ERROR (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Error from shard: https://127.0.0.1:62538/solr
[junit4] 2> org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:62538/solr: Error CREATEing SolrCore 'coll1_shard2_replica_n2': Unable to create core [coll1_shard2_replica_n2] Caused by: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:172) ~[java/:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_144]
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_144]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_144]
[junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) ~[metrics-core-3.2.2.jar:3.2.2]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:192) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_144]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_144]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_144]
[junit4] 2> 2642827 ERROR (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Error CREATEing SolrCore 'coll1_shard1_replica_n1': Unable to create core [coll1_shard1_replica_n1] Caused by: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:996)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:90)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:356)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:389)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:174)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:736)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:498)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:393)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:339)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:530)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:289)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:149)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Unable to create core [coll1_shard1_replica_n1]
[junit4] 2> at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1068)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:957)
[junit4] 2> ... 42 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Could not load conf for core coll1_shard1_replica_n1: Can't load schema managed-schema: org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:97)
[junit4] 2> at org.apache.solr.core.CoreContainer.getConfigSet(CoreContainer.java:1096)
[junit4] 2> at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1043)
[junit4] 2> ... 43 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Can't load schema managed-schema: org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.schema.IndexSchema.readSchema(IndexSchema.java:582)
[junit4] 2> at org.apache.solr.schema.IndexSchema.<init>(IndexSchema.java:179)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchema.<init>(ManagedIndexSchema.java:105)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:173)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchemaFactory.create(ManagedIndexSchemaFactory.java:45)
[junit4] 2> at org.apache.solr.schema.IndexSchemaFactory.buildIndexSchema(IndexSchemaFactory.java:75)
[junit4] 2> at org.apache.solr.core.ConfigSetService.createIndexSchema(ConfigSetService.java:119)
[junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:92)
[junit4] 2> ... 45 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchema.informResourceLoaderAwareObjectsInChain(ManagedIndexSchema.java:1317)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchema.informResourceLoaderAwareObjectsForFieldType(ManagedIndexSchema.java:1168)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchema.postReadInform(ManagedIndexSchema.java:1153)
[junit4] 2> at org.apache.solr.schema.IndexSchema.readSchema(IndexSchema.java:578)
[junit4] 2> ... 52 more
[junit4] 2> Caused by: org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoader.java:130)
[junit4] 2> at org.apache.lucene.analysis.util.AbstractAnalysisFactory.getLines(AbstractAnalysisFactory.java:254)
[junit4] 2> at org.apache.lucene.analysis.util.AbstractAnalysisFactory.getWordSet(AbstractAnalysisFactory.java:243)
[junit4] 2> at org.apache.lucene.analysis.util.ElisionFilterFactory.inform(ElisionFilterFactory.java:59)
[junit4] 2> at org.apache.solr.schema.ManagedIndexSchema.informResourceLoaderAwareObjectsInChain(ManagedIndexSchema.java:1315)
[junit4] 2> ... 55 more
[junit4] 2>
[junit4] 2> 2642829 INFO (qtp1450443904-27654) [n:127.0.0.1:62539_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=coll1.AUTOCREATED&newCollection=true&name=coll1_shard1_replica_n1&action=CREATE&numShards=2&collection=coll1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=400 QTime=1737
[junit4] 2> 2642833 ERROR (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Error from shard: https://127.0.0.1:62539/solr
[junit4] 2> org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:62539/solr: Error CREATEing SolrCore 'coll1_shard1_replica_n1': Unable to create core [coll1_shard1_replica_n1] Caused by: Can't find resource 'lang/contractions_ca.txt' in classpath or '/configs/coll1.AUTOCREATED', cwd=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) ~[java/:?]
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) ~[java/:?]
[junit4] 2> at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:172) ~[java/:?]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_144]
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_144]
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:1.8.0_144]
[junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) ~[metrics-core-3.2.2.jar:3.2.2]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:192) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_144]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_144]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_144]
[junit4] 2> 2642836 ERROR (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Cleaning up collection [coll1].
[junit4] 2> 2642836 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true, asyncId=null
[junit4] 2> 2642841 INFO (qtp269697565-27646) [n:127.0.0.1:62538_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=coll1_shard2_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2642841 INFO (qtp1450443904-27657) [n:127.0.0.1:62539_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=coll1_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 2642957 INFO (zkCallback-8434-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2642957 INFO (zkCallback-8434-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2642957 INFO (zkCallback-8436-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2642957 INFO (zkCallback-8436-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/coll1/state.json] for collection [coll1] has occurred - updating... (live nodes size: [2])
[junit4] 2> 2643563 INFO (OverseerThreadFactory-9844-thread-1) [ ] o.a.s.c.a.c.CreateCollectionCmd Cleaned up artifacts for failed create collection for [coll1]
[junit4] 2> 2643569 INFO (qtp1450443904-27656) [n:127.0.0.1:62539_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 2644491 INFO (OverseerCollectionConfigSetProcessor-72088043796037638-127.0.0.1:62538_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2673664 ERROR (qtp1450443904-27656) [n:127.0.0.1:62539_solr ] o.a.s.h.a.CollectionsHandler Timed out waiting for new collection's replicas to become ACTIVE with timeout=30
[junit4] 2> 2673664 INFO (qtp1450443904-27656) [n:127.0.0.1:62539_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&replicationFactor=1&name=coll1&nrtReplicas=1&action=CREATE&numShards=2&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=33198
[junit4] 2> 2673666 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[2E23771CAD798DE8]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRestartZkWhenClusterDown
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkFailoverTest -Dtests.method=testRestartZkWhenClusterDown -Dtests.seed=2E23771CAD798DE8 -Dtests.slow=true -Dtests.locale=sq-AL -Dtests.timezone=Europe/Guernsey -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 33.4s J0 | ZkFailoverTest.testRestartZkWhenClusterDown <<<
[junit4] > Throwable #1: org.apache.solr.common.SolrException: Collection not found: coll1
[junit4] > at __randomizedtesting.SeedInfo.seed([2E23771CAD798DE8:40B4F450D49E2B6B]:0)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:853)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:819)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
[junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173)
[junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
[junit4] > at org.apache.solr.cloud.ZkFailoverTest.testRestartZkWhenClusterDown(ZkFailoverTest.java:50)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 2673669 INFO (jetty-closer-8419-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6ab10c58{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 2673670 INFO (jetty-closer-8419-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@56f11574{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 2673670 INFO (jetty-closer-8419-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=333876104
[junit4] 2> 2673672 INFO (jetty-closer-8419-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2673672 INFO (jetty-closer-8419-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b021fc6: rootName = solr_62539, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@92a9055
[junit4] 2> 2673672 INFO (jetty-closer-8419-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1099878303
[junit4] 2> 2673672 INFO (jetty-closer-8419-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2673672 INFO (jetty-closer-8419-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4a8e0b12: rootName = solr_62538, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@dde41dc
[junit4] 2> 2673677 INFO (jetty-closer-8419-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2673677 INFO (jetty-closer-8419-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@76ce3a6f: rootName = solr_62539, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1333e534
[junit4] 2> 2673678 INFO (jetty-closer-8419-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2673678 INFO (jetty-closer-8419-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@76bbc168: rootName = solr_62538, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1333e534
[junit4] 2> 2673680 INFO (jetty-closer-8419-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2673680 INFO (jetty-closer-8419-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@76c16d02: rootName = solr_62539, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2daaa630
[junit4] 2> 2673680 INFO (jetty-closer-8419-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2673680 INFO (jetty-closer-8419-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2646e3fa: rootName = solr_62538, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2daaa630
[junit4] 2> 2673682 INFO (jetty-closer-8419-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:62538_solr
[junit4] 2> 2673682 INFO (jetty-closer-8419-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:62539_solr
[junit4] 2> 2673684 INFO (jetty-closer-8419-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2673684 INFO (jetty-closer-8419-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2673684 INFO (jetty-closer-8419-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72088043796037638-127.0.0.1:62538_solr-n_0000000000) closing
[junit4] 2> 2673685 INFO (OverseerStateUpdate-72088043796037638-127.0.0.1:62538_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:62538_solr
[junit4] 2> 2673685 INFO (zkCallback-8434-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (0)
[junit4] 2> 2673685 INFO (zkCallback-8450-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (0)
[junit4] 2> 2673685 INFO (zkCallback-8436-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (0)
[junit4] 2> 2673685 INFO (zkCallback-8445-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (0)
[junit4] 2> 2673685 WARN (OverseerAutoScalingTriggerThread-72088043796037638-127.0.0.1:62538_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2673694 INFO (jetty-closer-8419-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4d4f6c21{/solr,null,UNAVAILABLE}
[junit4] 2> 2673694 INFO (jetty-closer-8419-thread-1) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 2673696 INFO (jetty-closer-8419-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4502afa5{/solr,null,UNAVAILABLE}
[junit4] 2> 2673700 INFO (jetty-closer-8419-thread-2) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 2673701 ERROR (SUITE-ZkFailoverTest-seed#[2E23771CAD798DE8]-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> 2673701 INFO (SUITE-ZkFailoverTest-seed#[2E23771CAD798DE8]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:62536 62536
[junit4] 2> 2673747 INFO (Thread-4785) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:62536 62536
[junit4] 2> 2673952 WARN (Thread-4785) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/coll1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/collections/coll1/state.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ZkFailoverTest_2E23771CAD798DE8-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=161, maxMBSortInHeap=7.531642887835609, sim=RandomSimilarity(queryNorm=true): {}, locale=sq-AL, timezone=Europe/Guernsey
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=188846808,total=464850944
[junit4] 2> NOTE: All tests run in this JVM: [SecurityConfHandlerTest, TestFieldCacheSort, ClassificationUpdateProcessorTest, CreateCollectionCl
[...truncated too long message...]
| modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 1 | 0 | 0 | 0 || 1 | 0 |
---------------------------------------------------------------------
-init-totals:
-test:
[mkdir] Created dir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test
[junit4:pickseed] Seed property 'tests.seed' already defined: 2E23771CAD798DE8
[mkdir] Created dir: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/temp
[junit4] <JUnit4> says שלום! Master seed: 2E23771CAD798DE8
[junit4] Executing 10 suites with 2 JVMs.
[junit4]
[junit4] Started J0 PID(79243@serv1-vm2.sd-datasolutions.de).
[junit4] Started J1 PID(79242@serv1-vm2.sd-datasolutions.de).
[junit4] Suite: org.apache.solr.cloud.TestLockTree
[junit4] OK 0.43s J1 | TestLockTree.testLocks
[junit4] Completed [1/10] on J1 in 8.32s, 1 test
[junit4]
[junit4] Suite: org.apache.solr.cloud.TestLockTree
[junit4] OK 0.01s J1 | TestLockTree.testLocks
[junit4] Completed [2/10] on J1 in 0.04s, 1 test
[junit4]
[junit4] Duplicate suite name used with XML reports: org.apache.solr.cloud.TestLockTree. This may confuse tools that process XML reports. Set 'ignoreDuplicateSuites' to true to skip this message.
[junit4] HEARTBEAT J1 PID(79242@serv1-vm2.sd-datasolutions.de): 2018-04-08T15:30:21, stalled for 67.8s at: ZkFailoverTest.testRestartZkWhenClusterDown
[junit4] HEARTBEAT J0 PID(79243@serv1-vm2.sd-datasolutions.de): 2018-04-08T15:30:21, stalled for 65.8s at: ZkFailoverTest.testRestartZkWhenClusterDown
[junit4] Suite: org.apache.solr.cloud.ZkFailoverTest
[junit4] OK 29.1s J1 | ZkFailoverTest.testRestartZkWhenClusterDown
[junit4] Completed [3/10] on J1 in 139.32s, 1 test
[junit4]
[junit4] Suite: org.apache.solr.cloud.TestLockTree
[junit4] OK 0.01s J1 | TestLockTree.testLocks
[junit4] Completed [4/10] on J1 in 0.04s, 1 test
[junit4]
[junit4] Suite: org.apache.solr.cloud.ZkFailoverTest
[junit4] OK 31.0s J0 | ZkFailoverTest.testRestartZkWhenClusterDown
[junit4] Completed [5/10] on J0 in 148.83s, 1 test
[junit4]
[junit4] Duplicate suite name used with XML reports: org.apache.solr.cloud.ZkFailoverTest. This may confuse tools that process XML reports. Set 'ignoreDuplicateSuites' to true to skip this message.
[junit4] HEARTBEAT J1 PID(79242@serv1-vm2.sd-datasolutions.de): 2018-04-08T15:32:09, stalled for 61.1s at: ZkFailoverTest.testRestartZkWhenClusterDown
[junit4] HEARTBEAT J0 PID(79243@serv1-vm2.sd-datasolutions.de): 2018-04-08T15:32:21, stalled for 69.9s at: ZkFailoverTest.testRestartZkWhenClusterDown
[junit4] Suite: org.apache.solr.cloud.ZkFailoverTest
[junit4] OK 13.2s J1 | ZkFailoverTest.testRestartZkWhenClusterDown
[junit4] Completed [6/10] on J1 in 118.36s, 1 test
[junit4]
[junit4] Suite: org.apache.solr.cloud.ZkFailoverTest
[junit4] OK 15.2s J0 | ZkFailoverTest.testRestartZkWhenClusterDown
[junit4] Completed [7/10] on J0 in 118.08s, 1 test
[junit4]
[junit4] Suite: org.apache.solr.cloud.TestLockTree
[junit4] OK 0.10s J0 | TestLockTree.testLocks
[junit4] Completed [8/10] on J0 in 0.15s, 1 test
[junit4]
[junit4] Suite: org.apache.solr.cloud.TestLockTree
[junit4] OK 0.01s J0 | TestLockTree.testLocks
[junit4] Completed [9/10] on J0 in 0.03s, 1 test
[junit4]
[junit4] HEARTBEAT J1 PID(79242@serv1-vm2.sd-datasolutions.de): 2018-04-08T15:34:09, stalled for 63.8s at: ZkFailoverTest.testRestartZkWhenClusterDown
[junit4] Suite: org.apache.solr.cloud.ZkFailoverTest
[junit4] OK 11.6s J1 | ZkFailoverTest.testRestartZkWhenClusterDown
[junit4] Completed [10/10] on J1 in 113.35s, 1 test
[junit4]
[junit4] JVM J0: 1.00 .. 269.97 = 268.97s
[junit4] JVM J1: 1.00 .. 382.62 = 381.61s
[junit4] Execution time total: 6 minutes 22 seconds
[junit4] Tests summary: 10 suites, 10 tests
[junit4] Could not remove temporary path: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J1/temp])
[junit4] Could not remove temporary path: /Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/solr-core/test/J0/temp])
[echo] 5 slowest tests:
[junit4:tophints] 125.95s | org.apache.solr.schema.TestCloudSchemaless
[junit4:tophints] 116.93s | org.apache.solr.update.SoftAutoCommitTest
[junit4:tophints] 112.28s | org.apache.solr.cloud.ZkFailoverTest
[junit4:tophints] 106.66s | org.apache.solr.cloud.api.collections.AssignTest
[junit4:tophints] 98.45s | org.apache.solr.cloud.TestTlogReplica
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 6 minutes 25 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.TestLockTree
[repro] 0/5 failed: org.apache.solr.cloud.ZkFailoverTest
[repro] Exiting with code 0
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$34.hasMatch(FilePath.java:2639)
at hudson.FilePath$34.invoke(FilePath.java:2518)
at hudson.FilePath$34.invoke(FilePath.java:2508)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2879)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to MacOSX VBOX
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:955)
at hudson.FilePath.act(FilePath.java:998)
at hudson.FilePath.act(FilePath.java:987)
at hudson.FilePath.validateAntFileMask(FilePath.java:2508)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1752)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2881)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath.act(FilePath.java:1000)
at hudson.FilePath.act(FilePath.java:987)
at hudson.FilePath.validateAntFileMask(FilePath.java:2508)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1752)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error?
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=aaddf2ee4267dea3b54990dfeb826a2392dc6fec, workspace=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX
[WARNINGS] Computing warning deltas based on reference build #564
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2