Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-7.x/154/

2 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest

Error Message:
1 thread leaked from SUITE scope at 
org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest:     1) 
Thread[id=137, name=qtp980971333-137, state=TIMED_WAITING, 
group=TGRP-TimeRoutedAliasUpdateProcessorTest]         at 
sun.misc.Unsafe.park(Native Method)         at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)         
at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) 
        at java.lang.Thread.run(Thread.java:748)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest: 
   1) Thread[id=137, name=qtp980971333-137, state=TIMED_WAITING, 
group=TGRP-TimeRoutedAliasUpdateProcessorTest]
        at sun.misc.Unsafe.park(Native Method)
        at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
        at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
        at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
        at java.lang.Thread.run(Thread.java:748)
        at __randomizedtesting.SeedInfo.seed([BDFFAB212FC1DDCD]:0)


FAILED:  
junit.framework.TestSuite.org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest

Error Message:
There are still zombie threads that couldn't be terminated:    1) 
Thread[id=137, name=qtp980971333-137, state=TIMED_WAITING, 
group=TGRP-TimeRoutedAliasUpdateProcessorTest]         at 
sun.misc.Unsafe.park(Native Method)         at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)         
at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) 
        at java.lang.Thread.run(Thread.java:748)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   1) Thread[id=137, name=qtp980971333-137, state=TIMED_WAITING, 
group=TGRP-TimeRoutedAliasUpdateProcessorTest]
        at sun.misc.Unsafe.park(Native Method)
        at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
        at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
        at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
        at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
        at java.lang.Thread.run(Thread.java:748)
        at __randomizedtesting.SeedInfo.seed([BDFFAB212FC1DDCD]:0)




Build Log:
[...truncated 12950 lines...]
   [junit4] Suite: 
org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/init-core-data-001
   [junit4]   2> 30294 WARN  
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[BDFFAB212FC1DDCD]-worker) [    
] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9
   [junit4]   2> 30306 INFO  
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[BDFFAB212FC1DDCD]-worker) [    
] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 30308 INFO  
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[BDFFAB212FC1DDCD]-worker) [    
] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 30309 INFO  
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[BDFFAB212FC1DDCD]-worker) [    
] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 30344 INFO  
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[BDFFAB212FC1DDCD]-worker) [    
] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001
   [junit4]   2> 30474 INFO  
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[BDFFAB212FC1DDCD]-worker) [    
] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 30514 INFO  (Thread-42) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 30514 INFO  (Thread-42) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 30834 INFO  
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[BDFFAB212FC1DDCD]-worker) [    
] o.a.s.c.ZkTestServer start zk server on port:45034
   [junit4]   2> 31593 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Exception causing close of session 0x0: ZooKeeperServer 
not running
   [junit4]   2> 31735 ERROR (Thread-42) [    ] 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> 33634 INFO  (zkConnectionManagerCallback-17-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 33977 INFO  (jetty-launcher-14-thread-1) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-22T01:27:37+04:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 33977 INFO  (jetty-launcher-14-thread-2) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-22T01:27:37+04:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 34018 INFO  (jetty-launcher-14-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 34018 INFO  (jetty-launcher-14-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 34018 INFO  (jetty-launcher-14-thread-1) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 34046 INFO  (jetty-launcher-14-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@425be33c{/solr,null,AVAILABLE}
   [junit4]   2> 34048 INFO  (jetty-launcher-14-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@4f73467{SSL,[ssl, 
http/1.1]}{127.0.0.1:45324}
   [junit4]   2> 34048 INFO  (jetty-launcher-14-thread-1) [    ] o.e.j.s.Server 
Started @36456ms
   [junit4]   2> 34048 INFO  (jetty-launcher-14-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=45324}
   [junit4]   2> 34049 ERROR (jetty-launcher-14-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 34049 INFO  (jetty-launcher-14-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 34049 INFO  (jetty-launcher-14-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 34049 INFO  (jetty-launcher-14-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 34049 INFO  (jetty-launcher-14-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-21T13:06:01.734Z
   [junit4]   2> 34105 INFO  (jetty-launcher-14-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 34139 INFO  (jetty-launcher-14-thread-1) [    ] 
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find 
system property or JNDI)
   [junit4]   2> 34139 INFO  (jetty-launcher-14-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 34139 INFO  (jetty-launcher-14-thread-2) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 34140 INFO  (jetty-launcher-14-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2cc4e842{/solr,null,AVAILABLE}
   [junit4]   2> 34141 INFO  (jetty-launcher-14-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@5ab8a620{SSL,[ssl, 
http/1.1]}{127.0.0.1:37989}
   [junit4]   2> 34142 INFO  (jetty-launcher-14-thread-2) [    ] o.e.j.s.Server 
Started @36549ms
   [junit4]   2> 34142 INFO  (jetty-launcher-14-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=37989}
   [junit4]   2> 34142 ERROR (jetty-launcher-14-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 34142 INFO  (jetty-launcher-14-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 34142 INFO  (jetty-launcher-14-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 34142 INFO  (jetty-launcher-14-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 34143 INFO  (jetty-launcher-14-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-21T13:06:01.827Z
   [junit4]   2> 34204 INFO  (zkConnectionManagerCallback-21-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 34204 INFO  (zkConnectionManagerCallback-19-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 34278 INFO  (jetty-launcher-14-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 34281 INFO  (jetty-launcher-14-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 34691 INFO  (jetty-launcher-14-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45034/solr
   [junit4]   2> 34713 INFO  (jetty-launcher-14-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45034/solr
   [junit4]   2> 34935 INFO  (zkConnectionManagerCallback-27-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 35000 INFO  (zkConnectionManagerCallback-29-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 35130 INFO  
(zkConnectionManagerCallback-31-thread-1-processing-n:127.0.0.1:37989_solr) 
[n:127.0.0.1:37989_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 35363 INFO  
(zkConnectionManagerCallback-33-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 38729 INFO  (jetty-launcher-14-thread-1) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 38763 INFO  (jetty-launcher-14-thread-1) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:45324_solr
   [junit4]   2> 38799 INFO  (jetty-launcher-14-thread-1) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.Overseer Overseer 
(id=72159552294682630-127.0.0.1:45324_solr-n_0000000000) starting
   [junit4]   2> 38922 INFO  (jetty-launcher-14-thread-2) 
[n:127.0.0.1:37989_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 38958 INFO  (jetty-launcher-14-thread-2) 
[n:127.0.0.1:37989_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:37989_solr
   [junit4]   2> 39137 INFO  
(zkCallback-30-thread-1-processing-n:127.0.0.1:37989_solr) 
[n:127.0.0.1:37989_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 39229 INFO  
(zkCallback-32-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 41016 INFO  (jetty-launcher-14-thread-1) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:45324_solr
   [junit4]   2> 41158 INFO  
(zkCallback-32-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 41159 INFO  
(zkCallback-30-thread-1-processing-n:127.0.0.1:37989_solr) 
[n:127.0.0.1:37989_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 41498 INFO  (jetty-launcher-14-thread-2) 
[n:127.0.0.1:37989_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37989.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ea3ad6f
   [junit4]   2> 42274 INFO  (jetty-launcher-14-thread-2) 
[n:127.0.0.1:37989_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37989.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ea3ad6f
   [junit4]   2> 42275 INFO  (jetty-launcher-14-thread-2) 
[n:127.0.0.1:37989_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37989.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ea3ad6f
   [junit4]   2> 42495 INFO  (jetty-launcher-14-thread-2) 
[n:127.0.0.1:37989_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/.
   [junit4]   2> 42741 INFO  (jetty-launcher-14-thread-1) 
[n:127.0.0.1:45324_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45324.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ea3ad6f
   [junit4]   2> 43300 INFO  (jetty-launcher-14-thread-1) 
[n:127.0.0.1:45324_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45324.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ea3ad6f
   [junit4]   2> 43301 INFO  (jetty-launcher-14-thread-1) 
[n:127.0.0.1:45324_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_45324.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ea3ad6f
   [junit4]   2> 43648 INFO  (jetty-launcher-14-thread-1) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node2/.
   [junit4]   2> 44710 INFO  (zkConnectionManagerCallback-39-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 45747 INFO  (qtp219882841-144) [n:127.0.0.1:37989_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with 
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 45819 INFO  (qtp219882841-144) [n:127.0.0.1:37989_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=72
   [junit4]   2> 46071 INFO  
(TEST-TimeRoutedAliasUpdateProcessorTest.test-seed#[BDFFAB212FC1DDCD]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 46568 INFO  (qtp980971333-135) [n:127.0.0.1:45324_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with 
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 46622 INFO  (qtp980971333-135) [n:127.0.0.1:45324_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=54
   [junit4]   2> 46625 INFO  (qtp980971333-133) [n:127.0.0.1:45324_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with 
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 46638 INFO  (qtp980971333-133) [n:127.0.0.1:45324_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=13
   [junit4]   2> 46750 INFO  (qtp980971333-136) [n:127.0.0.1:45324_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> 46806 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] 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> 46849 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/protwords.txt to /configs/timeConfig/protwords.txt
   [junit4]   2> 46879 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/managed-schema to /configs/timeConfig/managed-schema
   [junit4]   2> 46890 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/solrconfig.xml to /configs/timeConfig/solrconfig.xml
   [junit4]   2> 46910 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/synonyms.txt to /configs/timeConfig/synonyms.txt
   [junit4]   2> 46912 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/stopwords.txt to /configs/timeConfig/stopwords.txt
   [junit4]   2> 46923 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/params.json to /configs/timeConfig/params.json
   [junit4]   2> 46939 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_it.txt to 
/configs/timeConfig/lang/contractions_it.txt
   [junit4]   2> 46942 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_no.txt to 
/configs/timeConfig/lang/stopwords_no.txt
   [junit4]   2> 46944 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hy.txt to 
/configs/timeConfig/lang/stopwords_hy.txt
   [junit4]   2> 46959 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stemdict_nl.txt to 
/configs/timeConfig/lang/stemdict_nl.txt
   [junit4]   2> 46961 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_ca.txt to 
/configs/timeConfig/lang/contractions_ca.txt
   [junit4]   2> 46976 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_id.txt to 
/configs/timeConfig/lang/stopwords_id.txt
   [junit4]   2> 47008 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ga.txt to 
/configs/timeConfig/lang/stopwords_ga.txt
   [junit4]   2> 47011 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_da.txt to 
/configs/timeConfig/lang/stopwords_da.txt
   [junit4]   2> 47036 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hi.txt to 
/configs/timeConfig/lang/stopwords_hi.txt
   [junit4]   2> 47038 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ja.txt to 
/configs/timeConfig/lang/stopwords_ja.txt
   [junit4]   2> 47056 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_pt.txt to 
/configs/timeConfig/lang/stopwords_pt.txt
   [junit4]   2> 47058 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_hu.txt to 
/configs/timeConfig/lang/stopwords_hu.txt
   [junit4]   2> 47074 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_el.txt to 
/configs/timeConfig/lang/stopwords_el.txt
   [junit4]   2> 47093 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ru.txt to 
/configs/timeConfig/lang/stopwords_ru.txt
   [junit4]   2> 47095 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_tr.txt to 
/configs/timeConfig/lang/stopwords_tr.txt
   [junit4]   2> 47137 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ar.txt to 
/configs/timeConfig/lang/stopwords_ar.txt
   [junit4]   2> 47140 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/userdict_ja.txt to 
/configs/timeConfig/lang/userdict_ja.txt
   [junit4]   2> 47154 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_it.txt to 
/configs/timeConfig/lang/stopwords_it.txt
   [junit4]   2> 47156 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_eu.txt to 
/configs/timeConfig/lang/stopwords_eu.txt
   [junit4]   2> 47191 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_cz.txt to 
/configs/timeConfig/lang/stopwords_cz.txt
   [junit4]   2> 47193 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stoptags_ja.txt to 
/configs/timeConfig/lang/stoptags_ja.txt
   [junit4]   2> 47208 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_fr.txt to 
/configs/timeConfig/lang/contractions_fr.txt
   [junit4]   2> 47220 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fa.txt to 
/configs/timeConfig/lang/stopwords_fa.txt
   [junit4]   2> 47222 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_de.txt to 
/configs/timeConfig/lang/stopwords_de.txt
   [junit4]   2> 47246 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/contractions_ga.txt to 
/configs/timeConfig/lang/contractions_ga.txt
   [junit4]   2> 47265 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ca.txt to 
/configs/timeConfig/lang/stopwords_ca.txt
   [junit4]   2> 47267 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_nl.txt to 
/configs/timeConfig/lang/stopwords_nl.txt
   [junit4]   2> 47268 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_bg.txt to 
/configs/timeConfig/lang/stopwords_bg.txt
   [junit4]   2> 47270 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_es.txt to 
/configs/timeConfig/lang/stopwords_es.txt
   [junit4]   2> 47280 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_en.txt to 
/configs/timeConfig/lang/stopwords_en.txt
   [junit4]   2> 47282 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_lv.txt to 
/configs/timeConfig/lang/stopwords_lv.txt
   [junit4]   2> 47297 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_th.txt to 
/configs/timeConfig/lang/stopwords_th.txt
   [junit4]   2> 47303 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_ro.txt to 
/configs/timeConfig/lang/stopwords_ro.txt
   [junit4]   2> 47320 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fr.txt to 
/configs/timeConfig/lang/stopwords_fr.txt
   [junit4]   2> 47322 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_sv.txt to 
/configs/timeConfig/lang/stopwords_sv.txt
   [junit4]   2> 47332 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_fi.txt to 
/configs/timeConfig/lang/stopwords_fi.txt
   [junit4]   2> 47349 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/hyphenations_ga.txt to 
/configs/timeConfig/lang/hyphenations_ga.txt
   [junit4]   2> 47372 INFO  
(OverseerThreadFactory-78-thread-1-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/lang/stopwords_gl.txt to 
/configs/timeConfig/lang/stopwords_gl.txt
   [junit4]   2> 47453 INFO  (qtp980971333-136) [n:127.0.0.1:45324_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=702
   [junit4]   2> 47457 INFO  (qtp980971333-135) [n:127.0.0.1:45324_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with 
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 47495 INFO  (qtp980971333-135) [n:127.0.0.1:45324_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=37
   [junit4]   2> 47497 INFO  (qtp980971333-136) [n:127.0.0.1:45324_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with 
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 47497 INFO  (qtp980971333-136) [n:127.0.0.1:45324_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> 47499 INFO  (qtp980971333-133) [n:127.0.0.1:45324_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> 47521 INFO  
(OverseerThreadFactory-78-thread-2-processing-n:127.0.0.1:45324_solr) 
[n:127.0.0.1:45324_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
timeConfig
   [junit4]   2> 47522 INFO  
(OverseerCollectionConfigSetProcessor-72159552294682630-127.0.0.1:45324_solr-n_0000000000)
 [n:127.0.0.1:45324_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 47669 INFO  
(OverseerStateUpdate-72159552294682630-127.0.0.1:45324_solr-n_0000000000) 
[n:127.0.0.1:45324_solr    ] 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":"https://127.0.0.1:37989/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 47888 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 47890 INFO  (qtp219882841-145) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 48053 INFO  
(zkCallback-30-thread-2-processing-n:127.0.0.1:37989_solr) 
[n:127.0.0.1:37989_solr    ] 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> 48054 INFO  
(zkCallback-30-thread-1-processing-n:127.0.0.1:37989_solr) 
[n:127.0.0.1:37989_solr    ] 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> 49074 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 49075 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 49077 WARN  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/extraction/lib
 filtered by .*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/extraction/lib
   [junit4]   2> 49077 WARN  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
 filtered by solr-cell-\d.*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
   [junit4]   2> 49078 WARN  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/clustering/lib
 filtered by .*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/clustering/lib
   [junit4]   2> 49078 WARN  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
 filtered by solr-clustering-\d.*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
   [junit4]   2> 49078 WARN  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/langid/lib
 filtered by .*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/langid/lib
   [junit4]   2> 49099 WARN  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
 filtered by solr-langid-\d.*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
   [junit4]   2> 49099 WARN  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/velocity/lib
 filtered by .*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/velocity/lib
   [junit4]   2> 49099 WARN  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
 filtered by solr-velocity-\d.*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
   [junit4]   2> 49159 INFO  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 49831 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 52313 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 52524 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 52570 INFO  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_37989.solr.core.timeConfig.shard1.replica_n1' (registry 
'solr.core.timeConfig.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ea3ad6f
   [junit4]   2> 52570 INFO  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 52570 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/./timeConfig_shard1_replica_n1/data/]
   [junit4]   2> 52813 INFO  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
   [junit4]   2> 54260 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54260 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54262 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54262 INFO  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 54294 INFO  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3ca8f21a[timeConfig_shard1_replica_n1] main]
   [junit4]   2> 54311 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54329 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54331 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54418 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54419 INFO  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 54461 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54642 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54653 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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 1593015934478450688
   [junit4]   2> 54658 INFO  
(searcherExecutor-82-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener 
sending requests to Searcher@3ca8f21a[timeConfig_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 54658 INFO  
(searcherExecutor-82-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener 
done.
   [junit4]   2> 54658 INFO  
(searcherExecutor-82-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_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> 54716 INFO  
(searcherExecutor-82-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_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@3ca8f21a[timeConfig_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 54844 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54844 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54854 INFO  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/
   [junit4]   2> 54855 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54856 INFO  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.SyncStrategy https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ 
has no replicas
   [junit4]   2> 54856 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54923 INFO  (qtp219882841-145) [n:127.0.0.1:37989_solr 
c:timeConfig s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ shard1
   [junit4]   2> 54952 INFO  
(zkCallback-30-thread-1-processing-n:127.0.0.1:37989_solr) 
[n:127.0.0.1:37989_solr    ] 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> 54952 INFO  
(zkCallback-30-thread-2-processing-n:127.0.0.1:37989_solr) 
[n:127.0.0.1:37989_solr    ] 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> 54987 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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> 54991 INFO  (qtp219882841-145) [n:127.0.0.1:37989_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=7104
   [junit4]   2> 55043 INFO  (qtp980971333-133) [n:127.0.0.1:45324_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> 55187 INFO  
(zkCallback-30-thread-2-processing-n:127.0.0.1:37989_solr) 
[n:127.0.0.1:37989_solr    ] 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> 55187 INFO  
(zkCallback-30-thread-1-processing-n:127.0.0.1:37989_solr) 
[n:127.0.0.1:37989_solr    ] 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> 55602 INFO  
(OverseerCollectionConfigSetProcessor-72159552294682630-127.0.0.1:45324_solr-n_0000000000)
 [n:127.0.0.1:45324_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 56162 INFO  (qtp980971333-133) [n:127.0.0.1:45324_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=8663
   [junit4]   2> 56187 INFO  (qtp980971333-135) [n:127.0.0.1:45324_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with 
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 56207 INFO  (qtp980971333-135) [n:127.0.0.1:45324_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={action=CLUSTERSTATUS&wt=javabin&version=2} status=0 QTime=20
   [junit4]   2> 56223 INFO  (qtp980971333-133) [n:127.0.0.1:45324_solr    ] 
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with 
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 56226 INFO  (qtp980971333-133) [n:127.0.0.1:45324_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> 56748 INFO  (qtp219882841-142) [n:127.0.0.1:37989_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> 56750 INFO  (qtp219882841-142) [n:127.0.0.1:37989_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> 56820 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_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> 56856 INFO  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.SolrCore config update listener called for core 
timeConfig_shard1_replica_n1
   [junit4]   2> 56857 INFO  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.SolrCore core reload timeConfig_shard1_replica_n1
   [junit4]   2> 56964 INFO  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.RequestParams conf resource params.json loaded . version : 0 
   [junit4]   2> 56964 INFO  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.RequestParams request params refreshed to version 0
   [junit4]   2> 56965 WARN  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/extraction/lib
 filtered by .*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/extraction/lib
   [junit4]   2> 57044 WARN  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
 filtered by solr-cell-\d.*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
   [junit4]   2> 57044 WARN  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/clustering/lib
 filtered by .*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/clustering/lib
   [junit4]   2> 57044 WARN  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
 filtered by solr-clustering-\d.*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
   [junit4]   2> 57044 WARN  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/langid/lib
 filtered by .*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/langid/lib
   [junit4]   2> 57045 WARN  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
 filtered by solr-langid-\d.*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
   [junit4]   2> 57045 WARN  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/velocity/lib
 filtered by .*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../contrib/velocity/lib
   [junit4]   2> 57045 WARN  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.SolrConfig Couldn't add files from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
 filtered by solr-velocity-\d.*\.jar to classpath: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1/../../../../dist
   [junit4]   2> 57130 INFO  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 57385 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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> 57386 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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=83
   [junit4]   2> 57386 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 57387 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 1 attempts
   [junit4]   2> 57387 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_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> 57388 INFO  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.s.IndexSchema [timeConfig_shard1_replica_n1] Schema name=default-config
   [junit4]   2> 57532 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 57551 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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=19
   [junit4]   2> 57563 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 57589 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 2 attempts
   [junit4]   2> 57589 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_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> 57717 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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> 57723 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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=5
   [junit4]   2> 57723 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 57750 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 3 attempts
   [junit4]   2> 57750 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_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> 57874 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 57874 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 57875 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 4 attempts
   [junit4]   2> 57876 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 1 
secs, maxWait 30
   [junit4]   2> 57888 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 57996 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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> 58031 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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=35
   [junit4]   2> 58032 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 58032 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 5 attempts
   [junit4]   2> 58032 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 1 
secs, maxWait 30
   [junit4]   2> 58151 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 58152 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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=1
   [junit4]   2> 58202 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 6 attempts
   [junit4]   2> 58202 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 1 
secs, maxWait 30
   [junit4]   2> 58202 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 58333 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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> 58352 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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=19
   [junit4]   2> 58368 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 58368 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 7 attempts
   [junit4]   2> 58368 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 1 
secs, maxWait 30
   [junit4]   2> 58526 INFO  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
   [junit4]   2> 58528 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 58543 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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=15
   [junit4]   2> 58544 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 58567 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 8 attempts
   [junit4]   2> 58567 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 1 
secs, maxWait 30
   [junit4]   2> 58691 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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> 58692 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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> 58693 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 9 attempts
   [junit4]   2> 58693 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 1 
secs, maxWait 30
   [junit4]   2> 58726 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 58797 INFO  (Thread-61) [n:127.0.0.1:37989_solr    ] 
o.a.s.c.CoreContainer Reloading SolrCore 'timeConfig_shard1_replica_n1' using 
configuration from collection timeConfig
   [junit4]   2> 58947 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 58953 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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=6
   [junit4]   2> 58954 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 58962 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 10 attempts
   [junit4]   2> 58962 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 2 
secs, maxWait 30
   [junit4]   2> 59148 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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> 59181 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 59181 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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=33
   [junit4]   2> 59182 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 11 attempts
   [junit4]   2> 59182 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 2 
secs, maxWait 30
   [junit4]   2> 59309 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 59327 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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=18
   [junit4]   2> 59328 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 59328 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 12 attempts
   [junit4]   2> 59328 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 2 
secs, maxWait 30
   [junit4]   2> 59424 INFO  (Thread-61) [n:127.0.0.1:37989_solr c:timeConfig 
s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter 
JMX monitoring for 'solr_37989.solr.core.timeConfig.shard1.replica_n1' 
(registry 'solr.core.timeConfig.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@5ea3ad6f
   [junit4]   2> 59457 INFO  (Thread-61) [n:127.0.0.1:37989_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 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/timeConfig_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J2/temp/solr.update.processor.TimeRoutedAliasUpdateProcessorTest_BDFFAB212FC1DDCD-001/tempDir-001/node1/./timeConfig_shard1_replica_n1/data/]
   [junit4]   2> 59439 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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> 59474 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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=34
   [junit4]   2> 59475 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 13 attempts
   [junit4]   2> 59475 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 2 
secs, maxWait 30
   [junit4]   2> 59475 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 59512 INFO  (Thread-61) [n:127.0.0.1:37989_solr c:timeConfig 
s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
   [junit4]   2> 59605 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 59605 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 59606 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 14 attempts
   [junit4]   2> 59606 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 2 
secs, maxWait 30
   [junit4]   2> 59620 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 59772 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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> 59787 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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=14
   [junit4]   2> 59787 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 59787 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 15 attempts
   [junit4]   2> 59788 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 2 
secs, maxWait 30
   [junit4]   2> 59918 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 59918 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 59920 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 16 attempts
   [junit4]   2> 59920 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 3 
secs, maxWait 30
   [junit4]   2> 59933 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 60064 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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> 60075 INFO  (qtp219882841-143) [n:127.0.0.1:37989_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=11
   [junit4]   2> 60091 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 17 attempts
   [junit4]   2> 60092 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 3 
secs, maxWait 30
   [junit4]   2> 60092 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 60126 INFO  (Thread-61) [n:127.0.0.1:37989_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> 60126 INFO  (Thread-61) [n:127.0.0.1:37989_solr c:timeConfig 
s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 60333 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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> 60348 INFO  (qtp219882841-146) [n:127.0.0.1:37989_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=15
   [junit4]   2> 60348 INFO  (SolrConfigHandler-refreshconf) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Another reload is in 
progress . Not doing anything
   [junit4]   2> 60349 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get 
expectedVersion 0 from 
https://127.0.0.1:37989/solr/timeConfig_shard1_replica_n1/ for prop overlay   
after 18 attempts
   [junit4]   2> 60349 INFO  
(solrHandlerExecutor-87-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 3 
secs, maxWait 30
   [junit4]   2> 60409 INFO  (Thread-61) [n:127.0.0.1:37989_solr c:timeConfig 
s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@4a124a19[timeConfig_shard1_replica_n1] main]
   [junit4]   2> 60412 INFO  (Thread-61) [n:127.0.0.1:37989_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> 60431 INFO  (Thread-61) [n:127.0.0.1:37989_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> 60431 INFO  (Thread-61) [n:127.0.0.1:37989_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> 60432 INFO  (Thread-61) [n:127.0.0.1:37989_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> 60449 INFO  (Thread-61) [n:127.0.0.1:37989_solr c:timeConfig 
s:shard1 r:core_node2 x:timeConfig_shard1_replica_n1] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 60449 INFO  (Thread-61) [n:127.0.0.1:37989_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> 60450 INFO  (Thread-61) [n:127.0.0.1:37989_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> 60461 INFO  
(searcherExecutor-88-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener 
sending requests to Searcher@4a124a19[timeConfig_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 60462 INFO  
(searcherExecutor-88-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c:timeConfig s:shard1 r:core_node2 
x:timeConfig_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener 
done.
   [junit4]   2> 60462 INFO  
(searcherExecutor-88-thread-1-processing-n:127.0.0.1:37989_solr 
x:timeConfig_shard1_replica_n1 s:shard1 c:timeConfig r:core_node2) 
[n:127.0.0.1:37989_solr c

[...truncated too long message...]

verseer Loop exiting : 127.0.0.1:45324_solr
   [junit4]   2> 118261 WARN  
(OverseerAutoScalingTriggerThread-72159552294682630-127.0.0.1:45324_solr-n_0000000000)
 [n:127.0.0.1:45324_solr    ] o.a.s.c.a.OverseerTriggerThread 
OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 118298 INFO  (jetty-closer-15-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2cc4e842{/solr,null,UNAVAILABLE}
   [junit4]   2> 118298 INFO  (jetty-closer-15-thread-2) [    ] o.e.j.s.session 
Stopped scavenging
   [junit4]   2> 118298 INFO  (jetty-closer-15-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@425be33c{/solr,null,UNAVAILABLE}
   [junit4]   2> 118299 INFO  (jetty-closer-15-thread-1) [    ] o.e.j.s.session 
Stopped scavenging
   [junit4]   2> 148301 WARN  (jetty-closer-15-thread-1) [    ] 
o.e.j.u.t.QueuedThreadPool 
QueuedThreadPool@qtp980971333{STOPPING,8<=10<=10000,i=0,q=1} Couldn't stop 
Thread[qtp980971333-137,5,TGRP-TimeRoutedAliasUpdateProcessorTest]
   [junit4]   2> 148302 ERROR 
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[BDFFAB212FC1DDCD]-worker) [    
] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 148303 INFO  
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[BDFFAB212FC1DDCD]-worker) [    
] o.a.s.c.ZkTestServer connecting to 127.0.0.1:45034 45034
   [junit4]   2> 148553 INFO  (Thread-42) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:45034 45034
   [junit4]   2> 148562 WARN  (Thread-42) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        10      /solr/configs/timeConfig/managed-schema
   [junit4]   2>        7       
/solr/collections/myalias_2017-10-23/terms/shard2
   [junit4]   2>        7       
/solr/collections/myalias_2017-10-23/terms/shard1
   [junit4]   2>        6       /solr/configs/timeConfig
   [junit4]   2>        4       /solr/aliases.json
   [junit4]   2>        3       /solr/collections/timeConfig/terms/shard1
   [junit4]   2>        2       
/solr/collections/myalias_2017-10-24/terms/shard1
   [junit4]   2>        2       
/solr/collections/myalias_2017-10-25/terms/shard1
   [junit4]   2>        2       
/solr/collections/myalias_2017-10-27/terms/shard1
   [junit4]   2>        2       
/solr/collections/myalias_2017-10-26/terms/shard1
   [junit4]   2>        2       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        12      /solr/collections/myalias_2017-10-23/state.json
   [junit4]   2>        6       /solr/collections/myalias_2017-10-27/state.json
   [junit4]   2>        6       /solr/collections/myalias_2017-10-26/state.json
   [junit4]   2>        5       /solr/collections/myalias_2017-10-25/state.json
   [junit4]   2>        5       /solr/collections/myalias_2017-10-24/state.json
   [junit4]   2>        5       /solr/collections/timeConfig/state.json
   [junit4]   2>        4       /solr/clusterprops.json
   [junit4]   2>        4       /solr/clusterstate.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        4       /solr/live_nodes
   [junit4]   2>        4       /solr/collections
   [junit4]   2> 
   [junit4]   2> 148562 INFO  
(SUITE-TimeRoutedAliasUpdateProcessorTest-seed#[BDFFAB212FC1DDCD]-worker) [    
] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> Feb 21, 2018 1:07:56 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> Feb 21, 2018 1:08:16 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> SEVERE: 1 thread leaked from SUITE scope at 
org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest: 
   [junit4]   2>    1) Thread[id=137, name=qtp980971333-137, 
state=TIMED_WAITING, group=TGRP-TimeRoutedAliasUpdateProcessorTest]
   [junit4]   2>         at sun.misc.Unsafe.park(Native Method)
   [junit4]   2>         at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]   2>         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> Feb 21, 2018 1:08:16 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: Starting to interrupt leaked threads:
   [junit4]   2>    1) Thread[id=137, name=qtp980971333-137, 
state=TIMED_WAITING, group=TGRP-TimeRoutedAliasUpdateProcessorTest]
   [junit4]   2> Feb 21, 2018 1:08:20 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> SEVERE: There are still zombie threads that couldn't be 
terminated:
   [junit4]   2>    1) Thread[id=137, name=qtp980971333-137, 
state=TIMED_WAITING, group=TGRP-TimeRoutedAliasUpdateProcessorTest]
   [junit4]   2>         at sun.misc.Unsafe.park(Native Method)
   [junit4]   2>         at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]   2>         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
   [junit4]   2>         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=694, maxMBSortInHeap=5.296522037108085, 
sim=RandomSimilarity(queryNorm=false): {}, locale=ar-EG, timezone=BET
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_152 (64-bit)/cpus=4,threads=2,free=210086600,total=317194240
   [junit4]   2> NOTE: All tests run in this JVM: 
[DistributedExpandComponentTest, TimeRoutedAliasUpdateProcessorTest]
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TimeRoutedAliasUpdateProcessorTest -Dtests.seed=BDFFAB212FC1DDCD 
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/test-data/enwiki.random.lines.txt
 -Dtests.locale=ar-EG -Dtests.timezone=BET -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J2 | TimeRoutedAliasUpdateProcessorTest (suite) <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.update.processor.TimeRoutedAliasUpdateProcessorTest: 
   [junit4]    >    1) Thread[id=137, name=qtp980971333-137, 
state=TIMED_WAITING, group=TGRP-TimeRoutedAliasUpdateProcessorTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
   [junit4]    >         at java.lang.Thread.run(Thread.java:748)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([BDFFAB212FC1DDCD]:0)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=137, name=qtp980971333-137, 
state=TIMED_WAITING, group=TGRP-TimeRoutedAliasUpdateProcessorTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at 
java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]    >         at 
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
   [junit4]    >         at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
   [junit4]    >         at java.lang.Thread.run(Thread.java:748)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([BDFFAB212FC1DDCD]:0)
   [junit4] Completed [13/777 (1!)] on J2 in 142.44s, 2 tests, 2 errors <<< 
FAILURES!

[...truncated 50906 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to