Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7275/ Java: 32bit/jdk1.8.0_144 -server -XX:+UseParallelGC
2 tests failed. FAILED: org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.test Error Message: Error from server at http://127.0.0.1:58813/solr: create the collection time out:180s Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:58813/solr: create the collection time out:180s at __randomizedtesting.SeedInfo.seed([889AC4EEA761B289:CEFB34099DDF71]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1106) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:886) 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.SolrRequest.process(SolrRequest.java:211) at org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest.test(TimeRoutedAliasUpdateProcessorTest.java:131) 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.common.util.TestTimeSource.testEpochTime Error Message: SimTimeSource:50.0 time diff=26214950 Stack Trace: java.lang.AssertionError: SimTimeSource:50.0 time diff=26214950 at __randomizedtesting.SeedInfo.seed([A39EC12C776FE59D:9BF2B209E3BF47DB]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:48) at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:32) 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 15180 lines...] [junit4] Suite: org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\init-core-data-001 [junit4] 2> 2941094 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[889AC4EEA761B289]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001 [junit4] 2> 2941095 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[889AC4EEA761B289]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2941095 INFO (Thread-7037) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2941095 INFO (Thread-7037) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2941099 ERROR (Thread-7037) [ ] 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> 2941195 INFO (SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[889AC4EEA761B289]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:58809 [junit4] 2> 2941198 INFO (zkConnectionManagerCallback-10825-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2941203 INFO (jetty-launcher-10822-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T11:12:37+13:45, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2941203 INFO (jetty-launcher-10822-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T11:12:37+13:45, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 2941203 INFO (jetty-launcher-10822-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2941203 INFO (jetty-launcher-10822-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2941203 INFO (jetty-launcher-10822-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 2941203 INFO (jetty-launcher-10822-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@199c8a7{/solr,null,AVAILABLE} [junit4] 2> 2941203 INFO (jetty-launcher-10822-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 2941203 INFO (jetty-launcher-10822-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 2941203 INFO (jetty-launcher-10822-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms [junit4] 2> 2941203 INFO (jetty-launcher-10822-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@f509bd{/solr,null,AVAILABLE} [junit4] 2> 2941204 INFO (jetty-launcher-10822-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@174bd28{HTTP/1.1,[http/1.1]}{127.0.0.1:58816} [junit4] 2> 2941204 INFO (jetty-launcher-10822-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@a65c54{HTTP/1.1,[http/1.1]}{127.0.0.1:58813} [junit4] 2> 2941204 INFO (jetty-launcher-10822-thread-2) [ ] o.e.j.s.Server Started @2941242ms [junit4] 2> 2941204 INFO (jetty-launcher-10822-thread-1) [ ] o.e.j.s.Server Started @2941242ms [junit4] 2> 2941204 INFO (jetty-launcher-10822-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58813} [junit4] 2> 2941204 INFO (jetty-launcher-10822-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58816} [junit4] 2> 2941204 ERROR (jetty-launcher-10822-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2941204 ERROR (jetty-launcher-10822-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 2941204 INFO (jetty-launcher-10822-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2941204 INFO (jetty-launcher-10822-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 2941204 INFO (jetty-launcher-10822-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2941206 INFO (jetty-launcher-10822-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 2941206 INFO (jetty-launcher-10822-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2941206 INFO (jetty-launcher-10822-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2941206 INFO (jetty-launcher-10822-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 2941206 INFO (jetty-launcher-10822-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-19T14:20:08.648Z [junit4] 2> 2941206 INFO (jetty-launcher-10822-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 2941206 INFO (jetty-launcher-10822-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-04-19T14:20:08.648Z [junit4] 2> 2941208 INFO (zkConnectionManagerCallback-10829-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2941208 INFO (zkConnectionManagerCallback-10827-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2941208 INFO (jetty-launcher-10822-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2941208 INFO (jetty-launcher-10822-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 2941220 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100259dc1420002, likely client has closed socket [junit4] 2> 2941227 INFO (jetty-launcher-10822-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58809/solr [junit4] 2> 2941227 INFO (jetty-launcher-10822-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58809/solr [junit4] 2> 2941229 INFO (zkConnectionManagerCallback-10837-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2941229 INFO (zkConnectionManagerCallback-10836-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2941230 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100259dc1420004, likely client has closed socket [junit4] 2> 2941231 INFO (zkConnectionManagerCallback-10839-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2941231 INFO (zkConnectionManagerCallback-10841-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2941307 INFO (jetty-launcher-10822-thread-1) [n:127.0.0.1:58813_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2941308 INFO (jetty-launcher-10822-thread-2) [n:127.0.0.1:58816_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2941308 INFO (jetty-launcher-10822-thread-1) [n:127.0.0.1:58813_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58813_solr [junit4] 2> 2941308 INFO (jetty-launcher-10822-thread-2) [n:127.0.0.1:58816_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58816_solr [junit4] 2> 2941309 INFO (jetty-launcher-10822-thread-1) [n:127.0.0.1:58813_solr ] o.a.s.c.Overseer Overseer (id=72098953520349189-127.0.0.1:58813_solr-n_0000000000) starting [junit4] 2> 2941309 INFO (zkCallback-10840-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2941309 INFO (zkCallback-10838-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2941317 INFO (zkConnectionManagerCallback-10850-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2941318 INFO (jetty-launcher-10822-thread-1) [n:127.0.0.1:58813_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2941319 INFO (jetty-launcher-10822-thread-1) [n:127.0.0.1:58813_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58809/solr ready [junit4] 2> 2941322 INFO (jetty-launcher-10822-thread-1) [n:127.0.0.1:58813_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2941322 INFO (jetty-launcher-10822-thread-1) [n:127.0.0.1:58813_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58813_solr [junit4] 2> 2941324 INFO (zkCallback-10840-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2941324 INFO (zkCallback-10838-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2941331 INFO (zkCallback-10849-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 2941613 INFO (jetty-launcher-10822-thread-2) [n:127.0.0.1:58816_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58816.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b62db [junit4] 2> 2941618 INFO (jetty-launcher-10822-thread-2) [n:127.0.0.1:58816_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58816.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b62db [junit4] 2> 2941618 INFO (jetty-launcher-10822-thread-2) [n:127.0.0.1:58816_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58816.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b62db [junit4] 2> 2941619 INFO (jetty-launcher-10822-thread-2) [n:127.0.0.1:58816_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node2\. [junit4] 2> 2941623 INFO (zkConnectionManagerCallback-10855-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2941624 INFO (jetty-launcher-10822-thread-2) [n:127.0.0.1:58816_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 2941625 INFO (jetty-launcher-10822-thread-2) [n:127.0.0.1:58816_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58809/solr ready [junit4] 2> 2941968 INFO (jetty-launcher-10822-thread-1) [n:127.0.0.1:58813_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58813.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b62db [junit4] 2> 2941972 INFO (jetty-launcher-10822-thread-1) [n:127.0.0.1:58813_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58813.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b62db [junit4] 2> 2941972 INFO (jetty-launcher-10822-thread-1) [n:127.0.0.1:58813_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58813.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b62db [junit4] 2> 2941973 INFO (jetty-launcher-10822-thread-1) [n:127.0.0.1:58813_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\. [junit4] 2> 2942000 INFO (zkConnectionManagerCallback-10857-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 2942006 INFO (qtp7002520-33136) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2942007 INFO (qtp7002520-33136) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2942021 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2942022 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2942023 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2942023 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2942024 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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> 2942025 INFO (OverseerThreadFactory-12503-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> 2942026 INFO (OverseerThreadFactory-12503-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/timeConfig/managed-schema [junit4] 2> 2942029 INFO (OverseerThreadFactory-12503-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/timeConfig/protwords.txt [junit4] 2> 2942030 INFO (OverseerThreadFactory-12503-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/timeConfig/solrconfig.xml [junit4] 2> 2942032 INFO (OverseerThreadFactory-12503-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/timeConfig/synonyms.txt [junit4] 2> 2942033 INFO (OverseerThreadFactory-12503-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/timeConfig/stopwords.txt [junit4] 2> 2942035 INFO (OverseerThreadFactory-12503-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> 2942037 INFO (OverseerThreadFactory-12503-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> 2942038 INFO (OverseerThreadFactory-12503-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> 2942040 INFO (OverseerThreadFactory-12503-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> 2942041 INFO (OverseerThreadFactory-12503-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> 2942043 INFO (OverseerThreadFactory-12503-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> 2942044 INFO (OverseerThreadFactory-12503-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> 2942046 INFO (OverseerThreadFactory-12503-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> 2942047 INFO (OverseerThreadFactory-12503-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> 2942049 INFO (OverseerThreadFactory-12503-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> 2942050 INFO (OverseerThreadFactory-12503-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> 2942052 INFO (OverseerThreadFactory-12503-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> 2942053 INFO (OverseerThreadFactory-12503-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> 2942055 INFO (OverseerThreadFactory-12503-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> 2942056 INFO (OverseerThreadFactory-12503-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> 2942058 INFO (OverseerThreadFactory-12503-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> 2942059 INFO (OverseerThreadFactory-12503-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> 2942060 INFO (OverseerThreadFactory-12503-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> 2942062 INFO (OverseerThreadFactory-12503-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> 2942063 INFO (OverseerThreadFactory-12503-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> 2942064 INFO (OverseerThreadFactory-12503-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> 2942066 INFO (OverseerThreadFactory-12503-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> 2942067 INFO (OverseerThreadFactory-12503-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> 2942069 INFO (OverseerThreadFactory-12503-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> 2942070 INFO (OverseerThreadFactory-12503-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> 2942071 INFO (OverseerThreadFactory-12503-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> 2942073 INFO (OverseerThreadFactory-12503-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> 2942074 INFO (OverseerThreadFactory-12503-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> 2942075 INFO (OverseerThreadFactory-12503-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> 2942077 INFO (OverseerThreadFactory-12503-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> 2942078 INFO (OverseerThreadFactory-12503-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> 2942079 INFO (OverseerThreadFactory-12503-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> 2942081 INFO (OverseerThreadFactory-12503-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> 2942082 INFO (OverseerThreadFactory-12503-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> 2942083 INFO (OverseerThreadFactory-12503-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> 2942084 INFO (OverseerThreadFactory-12503-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> 2942086 INFO (OverseerThreadFactory-12503-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> 2942088 INFO (OverseerThreadFactory-12503-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> 2942089 INFO (OverseerThreadFactory-12503-thread-1) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/timeConfig/params.json [junit4] 2> 2942090 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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=67 [junit4] 2> 2942092 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2942093 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2942093 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2942093 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2942094 INFO (qtp7002520-33142) [n:127.0.0.1:58813_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> 2942094 INFO (OverseerCollectionConfigSetProcessor-72098953520349189-127.0.0.1:58813_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> 2942094 INFO (OverseerThreadFactory-12503-thread-2) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection timeConfig [junit4] 2> 2942202 INFO (OverseerStateUpdate-72098953520349189-127.0.0.1:58813_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:58813/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2942404 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943427 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943427 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943428 WARN (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\extraction\lib [junit4] 2> 2943428 WARN (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist [junit4] 2> 2943429 WARN (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\clustering\lib [junit4] 2> 2943429 WARN (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist [junit4] 2> 2943429 WARN (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\langid\lib [junit4] 2> 2943430 WARN (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist [junit4] 2> 2943430 WARN (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\velocity\lib [junit4] 2> 2943430 WARN (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist [junit4] 2> 2943434 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2943450 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943569 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943594 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943595 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58813.solr.core.timeConfig.shard1.replica_n1' (registry 'solr.core.timeConfig.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b62db [junit4] 2> 2943595 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 2943595 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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 [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\.\timeConfig_shard1_replica_n1\data\] [junit4] 2> 2943598 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5 [junit4] 2> 2943663 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943663 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943665 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943665 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2943666 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e7436f[timeConfig_shard1_replica_n1] main] [junit4] 2> 2943667 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943668 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943668 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943668 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943668 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 2943669 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943670 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943671 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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 1598184605470425088 [junit4] 2> 2943671 INFO (searcherExecutor-12508-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@e7436f[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2943671 INFO (searcherExecutor-12508-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done. [junit4] 2> 2943671 INFO (searcherExecutor-12508-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_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> 2943671 INFO (searcherExecutor-12508-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_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@e7436f[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2943676 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943678 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943678 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943678 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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:58813/solr/timeConfig_shard1_replica_n1/ [junit4] 2> 2943678 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943679 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:58813/solr/timeConfig_shard1_replica_n1/ has no replicas [junit4] 2> 2943679 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943682 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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:58813/solr/timeConfig_shard1_replica_n1/ shard1 [junit4] 2> 2943783 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2943786 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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=1382 [junit4] 2> 2943788 INFO (qtp7002520-33142) [n:127.0.0.1:58813_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> 2943886 INFO (zkCallback-10838-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> 2944097 INFO (OverseerCollectionConfigSetProcessor-72098953520349189-127.0.0.1:58813_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> 2944789 INFO (qtp7002520-33142) [n:127.0.0.1:58813_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=2695 [junit4] 2> 2944790 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2944793 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 2944799 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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> 2944799 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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> 2944799 INFO (Thread-7056) [ ] o.a.s.c.SolrCore config update listener called for core timeConfig_shard1_replica_n1 [junit4] 2> 2944800 INFO (Thread-7056) [ ] o.a.s.c.SolrCore core reload timeConfig_shard1_replica_n1 [junit4] 2> 2944803 INFO (solrHandlerExecutor-12513-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_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> 2944816 INFO (Thread-7056) [ ] o.a.s.c.RequestParams conf resource params.json loaded . version : 0 [junit4] 2> 2944816 INFO (Thread-7056) [ ] o.a.s.c.RequestParams request params refreshed to version 0 [junit4] 2> 2944817 WARN (Thread-7056) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\extraction\lib [junit4] 2> 2944817 WARN (Thread-7056) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist [junit4] 2> 2944818 WARN (Thread-7056) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\clustering\lib [junit4] 2> 2944818 WARN (Thread-7056) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist [junit4] 2> 2944818 WARN (Thread-7056) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\langid\lib [junit4] 2> 2944819 WARN (Thread-7056) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist [junit4] 2> 2944819 WARN (Thread-7056) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\contrib\velocity\lib [junit4] 2> 2944819 WARN (Thread-7056) [ ] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1\..\..\..\..\dist [junit4] 2> 2944825 INFO (Thread-7056) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 2944840 INFO (Thread-7056) [ ] o.a.s.s.IndexSchema [timeConfig_shard1_replica_n1] Schema name=default-config [junit4] 2> 2944904 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2944904 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2944904 INFO (solrHandlerExecutor-12513-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_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:58813/solr/timeConfig_shard1_replica_n1/ for prop overlay after 1 attempts [junit4] 2> 2944904 INFO (solrHandlerExecutor-12513-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_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> 2944904 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything [junit4] 2> 2944959 INFO (Thread-7056) [ ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id [junit4] 2> 2944985 INFO (Thread-7056) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'timeConfig_shard1_replica_n1' using configuration from collection timeConfig [junit4] 2> 2945000 INFO (Thread-7056) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58813.solr.core.timeConfig.shard1.replica_n1' (registry 'solr.core.timeConfig.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4b62db [junit4] 2> 2945000 INFO (Thread-7056) [n:127.0.0.1:58813_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 [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\timeConfig_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\.\timeConfig_shard1_replica_n1\data\] [junit4] 2> 2945002 INFO (Thread-7056) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5 [junit4] 2> 2945004 INFO (qtp7002520-33142) [n:127.0.0.1:58813_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> 2945004 INFO (qtp7002520-33142) [n:127.0.0.1:58813_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> 2945004 INFO (solrHandlerExecutor-12513-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_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:58813/solr/timeConfig_shard1_replica_n1/ for prop overlay after 2 attempts [junit4] 2> 2945005 INFO (solrHandlerExecutor-12513-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_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> 2945005 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything [junit4] 2> 2945066 INFO (Thread-7056) [n:127.0.0.1:58813_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> 2945066 INFO (Thread-7056) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2945067 INFO (Thread-7056) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1abaab7[timeConfig_shard1_replica_n1] main] [junit4] 2> 2945068 INFO (Thread-7056) [n:127.0.0.1:58813_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> 2945069 INFO (Thread-7056) [n:127.0.0.1:58813_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> 2945069 INFO (Thread-7056) [n:127.0.0.1:58813_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> 2945069 INFO (Thread-7056) [n:127.0.0.1:58813_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> 2945069 INFO (Thread-7056) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 2945069 INFO (Thread-7056) [n:127.0.0.1:58813_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> 2945070 INFO (Thread-7056) [n:127.0.0.1:58813_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> 2945070 INFO (searcherExecutor-12514-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@1abaab7[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2945070 INFO (searcherExecutor-12514-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done. [junit4] 2> 2945070 INFO (searcherExecutor-12514-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_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> 2945070 INFO (Thread-7056) [n:127.0.0.1:58813_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 1598184606937382912 [junit4] 2> 2945071 INFO (searcherExecutor-12514-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_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@1abaab7[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2945072 INFO (Thread-7056) [n:127.0.0.1:58813_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> 2945072 INFO (Thread-7056) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@364826[timeConfig_shard1_replica_n1] main] [junit4] 2> 2945072 INFO (searcherExecutor-12514-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@364826[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2945072 INFO (searcherExecutor-12514-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done. [junit4] 2> 2945073 INFO (searcherExecutor-12514-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_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@364826[timeConfig_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2945105 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2945105 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2945105 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2945107 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Took 306.0ms to set the property overlay to be of version 0 for collection timeConfig [junit4] 2> 2945107 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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 params={wt=javabin&version=2} status=0 QTime=309 [junit4] 2> 2945107 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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@124725b [junit4] 2> 2945107 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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=124725b [junit4] 2> 2945107 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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@14401c8: rootName = solr_58813, 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@521688 [junit4] 2> 2945112 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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=124725b [junit4] 2> 2945113 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2945115 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=2 [junit4] 2> 2945117 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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> 2945118 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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> 2945118 INFO (Thread-7057) [ ] o.a.s.c.SolrCore config update listener called for core timeConfig_shard1_replica_n1 [junit4] 2> 2945118 INFO (Thread-7057) [ ] o.a.s.c.RequestParams conf resource params.json loaded . version : 1 [junit4] 2> 2945118 INFO (Thread-7057) [ ] o.a.s.c.RequestParams request params refreshed to version 1 [junit4] 2> 2945120 INFO (solrHandlerExecutor-12518-thread-1-processing-n:127.0.0.1:58813_solr x:timeConfig_shard1_replica_n1 c:timeConfig s:shard1 r:core_node2) [n:127.0.0.1:58813_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> 2945222 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2945222 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2945222 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2945223 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Took 105.0ms to set the property params to be of version 1 for collection timeConfig [junit4] 2> 2945224 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2945225 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 2945225 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2945226 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2945226 INFO (qtp7002520-33142) [n:127.0.0.1:58813_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> 2945228 INFO (OverseerThreadFactory-12503-thread-3) [ ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true, asyncId=null [junit4] 2> 2945229 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.timeConfig.shard1.replica_n1, tag=null [junit4] 2> 2945229 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e5f9c7: rootName = solr_58813, 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@521688 [junit4] 2> 2945236 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.c.SolrCore [timeConfig_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@eb1560 [junit4] 2> 2945236 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.timeConfig.shard1.replica_n1, tag=eb1560 [junit4] 2> 2945236 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.timeConfig.shard1.leader, tag=eb1560 [junit4] 2> 2945241 INFO (qtp7002520-33137) [n:127.0.0.1:58813_solr ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/timeConfig/terms/shard1 to Terms{values={}, version=1} [junit4] 2> 2945242 INFO (qtp7002520-33137) [n:127.0.0.1:58813_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=13 [junit4] 2> 2945346 INFO (zkCallback-10838-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/timeConfig/state.json] for collection [timeConfig] has occurred - updating... (live nodes size: [2]) [junit4] 2> 2945951 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=timeConfig&action=DELETE&wt=javabin&version=2} status=0 QTime=724 [junit4] 2> 2945955 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2945956 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2945956 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2945956 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2945956 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr ] o.a.s.h.a.ConfigSetsHandler Invoked ConfigSet Action :list with params action=LIST&wt=javabin&version=2 [junit4] 2> 2945956 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2945957 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2945957 INFO (qtp7002520-33142) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2945957 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 2945958 INFO (qtp7002520-33141) [n:127.0.0.1:58813_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 2945958 INFO (qtp7002520-33142) [n:127.0.0.1:58813_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> 2945959 INFO (OverseerCollectionConfigSetProcessor-72098953520349189-127.0.0.1:58813_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> 2945959 INFO (OverseerThreadFactory-12503-thread-4) [ ] o.a.s.c.a.c.CreateCollectionCmd Create collection myalias_2017-10-23 [junit4] 2> 2946168 INFO (OverseerStateUpdate-72098953520349189-127.0.0.1:58813_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:58813/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2946170 INFO (OverseerStateUpdate-72098953520349189-127.0.0.1:58813_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:58816/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2946171 INFO (OverseerStateUpdate-72098953520349189-127.0.0.1:58813_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:58813/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2946180 INFO (OverseerStateUpdate-72098953520349189-127.0.0.1:58813_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:58816/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 2946383 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2946384 INFO (qtp7002520-33140) [n:127.0.0.1:58813_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> 2946384 INFO (qtp25991370-33149) [n:127.0.0.1:58816_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> 2946384 INFO (qtp25991370-33150) [n:127.0.0.1:58816_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> 2946385 INFO (qtp25991370-33149) [n:127.0.0.1:58816_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 2947408 INFO (qtp7002520-33140) [n:127.0.0.1:58813_solr c:myalias_2017-10-23 s:shard2 r:core_node7 x:myalias_2017-10-23_shard2_replica_n4] o.a.s.c.RequestParams conf resource params.json loaded . version : 1 [junit4] 2> 2947408 INFO (qtp7002520-33140) [n:127.0.0.1:58813_solr c:myalias_2017-10-23 s:shard2 r:core_node7 x:myalias_2017-10-23_shard2_replica_n4] o.a.s.c.RequestParams request params refreshed to version 1 [junit4] 2> 2947408 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2947408 INFO (qtp7002520-33141) [n:127.0.0.1:58813_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> 2947409 WARN (qtp7002520-33140) [n:127.0.0.1:58813_solr c:myalias_2017-10-23 s:shard2 r:core_node7 x:myalias_2017-10-23_shard2_replica_n4] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\myalias_2017-10-23_shard2_replica_n4\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\myalias_2017-10-23_shard2_replica_n4\..\..\..\..\contrib\extraction\lib [junit4] 2> 2947409 WARN (qtp7002520-33141) [n:127.0.0.1:58813_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 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\myalias_2017-10-23_shard1_replica_n1\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\myalias_2017-10-23_shard1_replica_n1\..\..\..\..\contrib\extraction\lib [junit4] 2> 2947410 WARN (qtp7002520-33140) [n:127.0.0.1:58813_solr c:myalias_2017-10-23 s:shard2 r:core_node7 x:myalias_2017-10-23_shard2_replica_n4] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\myalias_2017-10-23_shard2_replica_n4\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\myalias_2017-10-23_shard2_replica_n4\..\..\..\..\dist [junit4] 2> 2947410 WARN (qtp7002520-33141) [n:127.0.0.1:58813_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 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\myalias_2017-10-23_shard1_replica_n1\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\myalias_2017-10-23_shard1_replica_n1\..\..\..\..\dist [junit4] 2> 2947410 WARN (qtp7002520-33140) [n:127.0.0.1:58813_solr c:myalias_2017-10-23 s:shard2 r:core_node7 x:myalias_2017-10-23_shard2_replica_n4] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\myalias_2017-10-23_shard2_replica_n4\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.processor.TimeRoutedAliasUpdateProcessorTest_889AC4EEA761B289-001\tempDir-001\node1\myalias_2017-10-23_shard2_replica_n4\..\..\..\..\contrib\clustering\lib [junit4] 2> 2947410 WARN (qtp7002520-33141) [n:127.0.0.1:58813_solr c:myalias_2017-10-23 s:shard1 r:core_node3 x:myalias_2017-10-23_shard1_rep [...truncated too long message...] > at > org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:48) [junit4] > at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:32) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.common.util.TestTimeSource_A39EC12C776FE59D-001 [junit4] 2> NOTE: test params are: codec=Lucene70, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@12d6ceb), locale=zh-HK, timezone=Atlantic/Cape_Verde [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=152654800,total=297533440 [junit4] 2> NOTE: All tests run in this JVM: [GraphExpressionTest, SolrExampleXMLTest, FieldValueEvaluatorTest, JDBCStreamTest, TestBatchUpdate, ShardParamsTest, GreaterThanEqualToEvaluatorTest, GetByIdTest, HttpSolrClientBuilderTest, SolrDocumentTest, FieldAnalysisResponseTest, TestHash, TestSolrProperties, TestCollectionStateWatchers, OrEvaluatorTest, AbsoluteValueEvaluatorTest, SquareRootEvaluatorTest, StreamExpressionToExplanationTest, TermsResponseTest, NormalizeEvaluatorTest, CorrelationEvaluatorTest, JettyWebappTest, ConcurrentUpdateSolrClientBuilderTest, StreamExpressionTest, SolrExampleEmbeddedTest, EqualToEvaluatorTest, RawValueEvaluatorTest, TestJsonRecordReader, TestCoreAdmin, RegressionEvaluatorTest, TestClusteringResponse, TestSpellCheckResponse, CubedRootEvaluatorTest, SelectWithEvaluatorsTest, RoundEvaluatorTest, SolrZkClientTest, SolrExampleStreamingTest, SolrExampleJettyTest, LargeVolumeBinaryJettyTest, LargeVolumeJettyTest, MergeIndexesEmbeddedTest, TestEmbeddedSolrServer, TestNamedListCodec, TestJavaBinCodec, TestDocumentObjectBinder, SolrQueryTest, ContentStreamTest, ModifiableSolrParamsTest, TestXMLEscaping, TestUpdateRequestCodec, NamedListTest, AnlysisResponseBaseTest, FacetFieldTest, UsingSolrJRefGuideExamplesTest, CollectionAdminRequestRequiredParamsTest, SolrSchemalessExampleTest, TestLBHttpSolrClient, TestPolicy, BasicHttpSolrClientTest, CloudSolrClientBuilderTest, CloudSolrClientCacheTest, CloudSolrClientMultiConstructorTest, CloudSolrClientTest, LengthEvaluatorTest, ModuloEvaluatorTest, NormalDistributionEvaluatorTest, PowerEvaluatorTest, ReverseEvaluatorTest, TangentEvaluatorTest, StreamExpressionParserTest, OperationsTest, TestConfigSetAdminRequest, TestV2Request, CommonAdminParamsTest, CommonParamsTest, TestPathTrie, TestRetryUtil, TestTimeSource] [junit4] Completed [149/151 (1!)] on J0 in 10.04s, 1 test, 1 failure <<< FAILURES! [...truncated 45181 lines...] [repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7275/consoleText [repro] Revision: a033759f127cec8137351a47dc4f6703941eab01 [repro] Ant options: "-Dargs=-server -XX:+UseParallelGC" [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr\core [repro] TimeRoutedAliasUpdateProcessorTest [repro] solr\solrj [repro] TestTimeSource [repro] ant compile-test [...truncated 3319 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TimeRoutedAliasUpdateProcessorTest" -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseParallelGC" -Dtests.seed=889AC4EEA761B289 -Dtests.slow=true -Dtests.locale=is -Dtests.timezone=America/Monterrey -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [...truncated 74 lines...] [repro] ant compile-test [...truncated 447 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestTimeSource" -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseParallelGC" -Dtests.seed=A39EC12C776FE59D -Dtests.slow=true -Dtests.locale=zh-HK -Dtests.timezone=Atlantic/Cape_Verde -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [...truncated 32 lines...] [junit4] Suite: org.apache.solr.common.util.TestTimeSource [junit4] 2> SLF4J: Class path contains multiple SLF4J bindings. [junit4] 2> SLF4J: Found binding in [jar:file:/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/solrj/test-lib/log4j-slf4j-impl-2.11.0.jar!/org/slf4j/impl/StaticLoggerBinder.class] [junit4] 2> SLF4J: Found binding in [jar:file:/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/lib/log4j-slf4j-impl-2.11.0.jar!/org/slf4j/impl/StaticLoggerBinder.class] [junit4] 2> SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation. [junit4] 2> SLF4J: Actual binding is of type [org.apache.logging.slf4j.Log4jLoggerFactory] [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.common.util.TestTimeSource_A39EC12C776FE59D-001\init-core-data-001 [junit4] 2> 9416 INFO (SUITE-TestTimeSource-seed#[A39EC12C776FE59D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 9596 INFO (SUITE-TestTimeSource-seed#[A39EC12C776FE59D]-worker) [ ] o.e.j.u.log Logging initialized @9633ms to org.eclipse.jetty.util.log.Slf4jLog [junit4] 2> 9619 INFO (SUITE-TestTimeSource-seed#[A39EC12C776FE59D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 9643 INFO (SUITE-TestTimeSource-seed#[A39EC12C776FE59D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 9669 INFO (TEST-TestTimeSource.testEpochTime-seed#[A39EC12C776FE59D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEpochTime [junit4] 2> 19696 INFO (TEST-TestTimeSource.testEpochTime-seed#[A39EC12C776FE59D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEpochTime [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestTimeSource -Dtests.method=testEpochTime -Dtests.seed=A39EC12C776FE59D -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=zh-HK -Dtests.timezone=Atlantic/Cape_Verde -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 10.1s J1 | TestTimeSource.testEpochTime <<< [junit4] > Throwable #1: java.lang.AssertionError: SimTimeSource:50.0 time diff=27765200 [junit4] > at __randomizedtesting.SeedInfo.seed([A39EC12C776FE59D:9BF2B209E3BF47DB]:0) [junit4] > at org.apache.solr.common.util.TestTimeSource.doTestEpochTime(TestTimeSource.java:48) [junit4] > at org.apache.solr.common.util.TestTimeSource.testEpochTime(TestTimeSource.java:32) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.common.util.TestTimeSource_A39EC12C776FE59D-001 [junit4] 2> NOTE: test params are: codec=Lucene70, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@14f832e), locale=zh-HK, timezone=Atlantic/Cape_Verde [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=40996432,total=78381056 [junit4] 2> NOTE: All tests run in this JVM: [TestTimeSource] [junit4] Completed [1/5 (1!)] on J1 in 18.60s, 1 test, 1 failure <<< FAILURES! [...truncated 27 lines...] BUILD FAILED C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1568: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1092: There were test failures: 5 suites, 5 tests, 1 failure [seed: A39EC12C776FE59D] Total time: 42 seconds [repro] Setting last failure code to 1 [repro] Failures: [repro] 0/5 failed: org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest [repro] 1/5 failed: org.apache.solr.common.util.TestTimeSource [repro] Exiting with code 1 C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build move /y solr\build solr\build.repro 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build 1 dir(s) moved. C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>exit 0 Archiving artifacts Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 [WARNINGS] Parsing warnings in console log with parser Java Compiler (javac) Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Setting ANT_1_8_2_HOME=C:\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=a033759f127cec8137351a47dc4f6703941eab01, workspace=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows [WARNINGS] Computing warning deltas based on reference build #7274 Recording test results Setting ANT_1_8_2_HOME=C:\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=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
