Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/351/
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CreateRoutedAliasTest
Error Message:
2 threads leaked from SUITE scope at
org.apache.solr.cloud.CreateRoutedAliasTest: 1) Thread[id=2110,
name=qtp1866480658-2110, state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
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) 2) Thread[id=2111,
name=qtp1866480658-2111, state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
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: 2 threads leaked from SUITE
scope at org.apache.solr.cloud.CreateRoutedAliasTest:
1) Thread[id=2110, name=qtp1866480658-2110, state=TIMED_WAITING,
group=TGRP-CreateRoutedAliasTest]
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)
2) Thread[id=2111, name=qtp1866480658-2111, state=TIMED_WAITING,
group=TGRP-CreateRoutedAliasTest]
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([D99E747A7D538712]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CreateRoutedAliasTest
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=2110, name=qtp1866480658-2110, state=TIMED_WAITING,
group=TGRP-CreateRoutedAliasTest] 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) 2) Thread[id=2111,
name=qtp1866480658-2111, state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
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=2110, name=qtp1866480658-2110, state=TIMED_WAITING,
group=TGRP-CreateRoutedAliasTest]
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)
2) Thread[id=2111, name=qtp1866480658-2111, state=TIMED_WAITING,
group=TGRP-CreateRoutedAliasTest]
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([D99E747A7D538712]:0)
Build Log:
[...truncated 11861 lines...]
[junit4] Suite: org.apache.solr.cloud.CreateRoutedAliasTest
[junit4] 2> 347477 INFO
(SUITE-CreateRoutedAliasTest-seed#[D99E747A7D538712]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/init-core-data-001
[junit4] 2> 347515 WARN
(SUITE-CreateRoutedAliasTest-seed#[D99E747A7D538712]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=11 numCloses=11
[junit4] 2> 347515 INFO
(SUITE-CreateRoutedAliasTest-seed#[D99E747A7D538712]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 347516 INFO
(SUITE-CreateRoutedAliasTest-seed#[D99E747A7D538712]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 347516 INFO
(SUITE-CreateRoutedAliasTest-seed#[D99E747A7D538712]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001
[junit4] 2> 347517 INFO
(SUITE-CreateRoutedAliasTest-seed#[D99E747A7D538712]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 347543 INFO (Thread-1114) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 347543 INFO (Thread-1114) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 347583 ERROR (Thread-1114) [ ] 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> 347644 INFO
(SUITE-CreateRoutedAliasTest-seed#[D99E747A7D538712]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:42737
[junit4] 2> 347646 INFO (zkConnectionManagerCallback-278-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 347649 INFO (jetty-launcher-275-thread-1) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 347649 INFO (jetty-launcher-275-thread-2) [ ]
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp:
2017-11-21T17:27:37-04:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 347653 INFO (jetty-launcher-275-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 347653 INFO (jetty-launcher-275-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 347653 INFO (jetty-launcher-275-thread-1) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 347653 INFO (jetty-launcher-275-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7ac5a471{/solr,null,AVAILABLE}
[junit4] 2> 347654 INFO (jetty-launcher-275-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@164feace{HTTP/1.1,[http/1.1]}{127.0.0.1:46424}
[junit4] 2> 347654 INFO (jetty-launcher-275-thread-1) [ ]
o.e.j.s.Server Started @360111ms
[junit4] 2> 347655 INFO (jetty-launcher-275-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=46424}
[junit4] 2> 347655 ERROR (jetty-launcher-275-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 347655 INFO (jetty-launcher-275-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 347655 INFO (jetty-launcher-275-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 347655 INFO (jetty-launcher-275-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 347655 INFO (jetty-launcher-275-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 347655 INFO (jetty-launcher-275-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 347655 INFO (jetty-launcher-275-thread-2) [ ]
o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 347655 INFO (jetty-launcher-275-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-03T21:24:59.740Z
[junit4] 2> 347655 INFO (jetty-launcher-275-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@27f6f20f{/solr,null,AVAILABLE}
[junit4] 2> 347685 INFO (jetty-launcher-275-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4f10bf94{HTTP/1.1,[http/1.1]}{127.0.0.1:36461}
[junit4] 2> 347685 INFO (jetty-launcher-275-thread-2) [ ]
o.e.j.s.Server Started @360141ms
[junit4] 2> 347685 INFO (jetty-launcher-275-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=36461}
[junit4] 2> 347685 ERROR (jetty-launcher-275-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 347685 INFO (jetty-launcher-275-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
7.3.0
[junit4] 2> 347685 INFO (jetty-launcher-275-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 347685 INFO (jetty-launcher-275-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 347685 INFO (jetty-launcher-275-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2018-02-03T21:24:59.770Z
[junit4] 2> 347717 INFO (zkConnectionManagerCallback-282-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 347741 INFO (zkConnectionManagerCallback-280-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 347746 INFO (jetty-launcher-275-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 347747 INFO (jetty-launcher-275-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 347802 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10001d166d60002, likely client has closed socket
[junit4] 2> 347807 INFO (jetty-launcher-275-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42737/solr
[junit4] 2> 347808 INFO (jetty-launcher-275-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42737/solr
[junit4] 2> 347809 INFO (zkConnectionManagerCallback-287-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 347810 INFO (zkConnectionManagerCallback-290-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 347818 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid
0x10001d166d60003, likely client has closed socket
[junit4] 2> 347820 INFO
(zkConnectionManagerCallback-292-thread-1-processing-n:127.0.0.1:46424_solr)
[n:127.0.0.1:46424_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 347887 INFO
(zkConnectionManagerCallback-294-thread-1-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348192 INFO (jetty-launcher-275-thread-2)
[n:127.0.0.1:36461_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 348207 INFO (jetty-launcher-275-thread-2)
[n:127.0.0.1:36461_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:36461_solr
[junit4] 2> 348208 INFO (jetty-launcher-275-thread-2)
[n:127.0.0.1:36461_solr ] o.a.s.c.Overseer Overseer
(id=72059592923021318-127.0.0.1:36461_solr-n_0000000000) starting
[junit4] 2> 348243 INFO (jetty-launcher-275-thread-2)
[n:127.0.0.1:36461_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36461_solr
[junit4] 2> 348277 INFO
(zkCallback-293-thread-1-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 348295 INFO (jetty-launcher-275-thread-1)
[n:127.0.0.1:46424_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 348331 INFO (jetty-launcher-275-thread-1)
[n:127.0.0.1:46424_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 348368 INFO (jetty-launcher-275-thread-1)
[n:127.0.0.1:46424_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:46424_solr
[junit4] 2> 348382 INFO
(zkCallback-293-thread-2-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 348403 INFO
(zkCallback-291-thread-1-processing-n:127.0.0.1:46424_solr)
[n:127.0.0.1:46424_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 348701 INFO (jetty-launcher-275-thread-2)
[n:127.0.0.1:36461_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36461.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2469f8e9
[junit4] 2> 348771 INFO (jetty-launcher-275-thread-2)
[n:127.0.0.1:36461_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36461.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2469f8e9
[junit4] 2> 348772 INFO (jetty-launcher-275-thread-2)
[n:127.0.0.1:36461_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_36461.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2469f8e9
[junit4] 2> 348773 INFO (jetty-launcher-275-thread-2)
[n:127.0.0.1:36461_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/.
[junit4] 2> 348996 INFO (jetty-launcher-275-thread-1)
[n:127.0.0.1:46424_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46424.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2469f8e9
[junit4] 2> 349051 INFO (jetty-launcher-275-thread-1)
[n:127.0.0.1:46424_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46424.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2469f8e9
[junit4] 2> 349051 INFO (jetty-launcher-275-thread-1)
[n:127.0.0.1:46424_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_46424.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@2469f8e9
[junit4] 2> 349052 INFO (jetty-launcher-275-thread-1)
[n:127.0.0.1:46424_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/.
[junit4] 2> 349306 INFO (zkConnectionManagerCallback-300-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 349361 INFO
(TEST-CreateRoutedAliasTest.testAliasNameMustBeValid-seed#[D99E747A7D538712]) [
] o.a.s.SolrTestCaseJ4 ###Starting testAliasNameMustBeValid
[junit4] 2> 349441 INFO (zkConnectionManagerCallback-305-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 349562 INFO
(TEST-CreateRoutedAliasTest.testAliasNameMustBeValid-seed#[D99E747A7D538712]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 349599 INFO
(TEST-CreateRoutedAliasTest.testAliasNameMustBeValid-seed#[D99E747A7D538712]) [
] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42737/solr
ready
[junit4] 2> 349635 INFO
(TEST-CreateRoutedAliasTest.testAliasNameMustBeValid-seed#[D99E747A7D538712]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 349737 INFO (qtp1866480658-2098) [n:127.0.0.1:36461_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :createroutedalias with
params
router.interval=%2B30MINUTE&router.start=2018-01-15T00:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&name=735741!45&router.name=time&create-collection.numShards=1&action=CREATEROUTEDALIAS&wt=json
and sendToOCPQueue=true
[junit4] 2> 349737 ERROR (qtp1866480658-2098) [n:127.0.0.1:36461_solr ]
o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Invalid alias:
[735741!45]. alias names must consist entirely of periods, underscores,
hyphens, and alphanumerics as well not start with a hyphen
[junit4] 2> at
org.apache.solr.client.solrj.util.SolrIdentifierValidator.validateName(SolrIdentifierValidator.java:39)
[junit4] 2> at
org.apache.solr.client.solrj.util.SolrIdentifierValidator.validateAliasName(SolrIdentifierValidator.java:52)
[junit4] 2> at
org.apache.solr.handler.admin.CollectionsHandler$CollectionOperation.lambda$static$6(CollectionsHandler.java:503)
[junit4] 2> at
org.apache.solr.handler.admin.CollectionsHandler$CollectionOperation.execute(CollectionsHandler.java:1064)
[junit4] 2> at
org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:245)
[junit4] 2> at
org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:232)
[junit4] 2> at
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:195)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:736)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:717)
[junit4] 2> at
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:498)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:380)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:527)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:530)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102)
[junit4] 2> at
org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140)
[junit4] 2> at
org.eclipse.jetty.util.thread.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>
[junit4] 2> 349738 INFO (qtp1866480658-2098) [n:127.0.0.1:36461_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={router.interval=%2B30MINUTE&router.start=2018-01-15T00:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&name=735741!45&router.name=time&create-collection.numShards=1&action=CREATEROUTEDALIAS&wt=json}
status=400 QTime=1
[junit4] 2> 349739 INFO
(TEST-CreateRoutedAliasTest.testAliasNameMustBeValid-seed#[D99E747A7D538712]) [
] o.a.s.SolrTestCaseJ4 ###Ending testAliasNameMustBeValid
[junit4] 2> 349812 INFO
(TEST-CreateRoutedAliasTest.testRandomRouterNameFails-seed#[D99E747A7D538712])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomRouterNameFails
[junit4] 2> 349874 INFO
(TEST-CreateRoutedAliasTest.testRandomRouterNameFails-seed#[D99E747A7D538712])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 349886 INFO (qtp790497909-2097) [n:127.0.0.1:46424_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :createroutedalias with
params
router.interval=%2B30MINUTE&router.start=2018-01-15T00:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&name=testRandomRouterNameFails&router.name=tiafasme&create-collection.numShards=1&action=CREATEROUTEDALIAS&wt=json
and sendToOCPQueue=true
[junit4] 2> 349935 ERROR
(OverseerThreadFactory-410-thread-1-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Collection: testRandomRouterNameFails operation: createroutedalias
failed:org.apache.solr.common.SolrException: Only 'time' routed aliases is
supported right now.
[junit4] 2> at
org.apache.solr.cloud.api.collections.TimeRoutedAlias.<init>(TimeRoutedAlias.java:134)
[junit4] 2> at
org.apache.solr.cloud.api.collections.CreateAliasCmd.callCreateRoutedAlias(CreateAliasCmd.java:116)
[junit4] 2> at
org.apache.solr.cloud.api.collections.CreateAliasCmd.call(CreateAliasCmd.java:65)
[junit4] 2> at
org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:254)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 349938 INFO (qtp790497909-2097) [n:127.0.0.1:46424_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={router.interval=%2B30MINUTE&router.start=2018-01-15T00:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&name=testRandomRouterNameFails&router.name=tiafasme&create-collection.numShards=1&action=CREATEROUTEDALIAS&wt=json}
status=400 QTime=51
[junit4] 2> 349952 INFO
(TEST-CreateRoutedAliasTest.testRandomRouterNameFails-seed#[D99E747A7D538712])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testRandomRouterNameFails
[junit4] 2> 350044 INFO
(TEST-CreateRoutedAliasTest.testBadDateMathIntervalFails-seed#[D99E747A7D538712])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testBadDateMathIntervalFails
[junit4] 2> 350062 INFO
(TEST-CreateRoutedAliasTest.testBadDateMathIntervalFails-seed#[D99E747A7D538712])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 350122 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :createroutedalias with
params
router.interval=%2B30MINUTEx&router.start=2018-01-15T00:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&router.max-future-ms=60000&name=testBadDateMathIntervalFails&router.name=time&create-collection.numShards=1&action=CREATEROUTEDALIAS&wt=json
and sendToOCPQueue=true
[junit4] 2> 350169 INFO
(OverseerCollectionConfigSetProcessor-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_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> 350193 ERROR
(OverseerThreadFactory-410-thread-2-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Collection: testBadDateMathIntervalFails operation: createroutedalias
failed:org.apache.solr.common.SolrException: bad router.interval,
java.text.ParseException: Unit not recognized: "MINUTEx"
[junit4] 2> at
org.apache.solr.cloud.api.collections.TimeRoutedAlias.<init>(TimeRoutedAlias.java:149)
[junit4] 2> at
org.apache.solr.cloud.api.collections.CreateAliasCmd.callCreateRoutedAlias(CreateAliasCmd.java:116)
[junit4] 2> at
org.apache.solr.cloud.api.collections.CreateAliasCmd.call(CreateAliasCmd.java:65)
[junit4] 2> at
org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:254)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.text.ParseException: Unit not recognized:
"MINUTEx"
[junit4] 2> at
org.apache.solr.util.DateMathParser.parseMath(DateMathParser.java:409)
[junit4] 2> at
org.apache.solr.cloud.api.collections.TimeRoutedAlias.<init>(TimeRoutedAlias.java:147)
[junit4] 2> ... 8 more
[junit4] 2>
[junit4] 2> 350269 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={router.interval=%2B30MINUTEx&router.start=2018-01-15T00:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&router.max-future-ms=60000&name=testBadDateMathIntervalFails&router.name=time&create-collection.numShards=1&action=CREATEROUTEDALIAS&wt=json}
status=400 QTime=146
[junit4] 2> 350284 INFO
(TEST-CreateRoutedAliasTest.testBadDateMathIntervalFails-seed#[D99E747A7D538712])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testBadDateMathIntervalFails
[junit4] 2> 350404 INFO
(TEST-CreateRoutedAliasTest.testV1-seed#[D99E747A7D538712]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testV1
[junit4] 2> 350552 INFO (qtp790497909-2100) [n:127.0.0.1:46424_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 350554 INFO (qtp790497909-2100) [n:127.0.0.1:46424_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> 350573 INFO
(TEST-CreateRoutedAliasTest.testV1-seed#[D99E747A7D538712]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 350575 INFO (qtp790497909-2100) [n:127.0.0.1:46424_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :createroutedalias with
params
router.interval=%2B30MINUTE&router.start=2018-02-03T21:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&name=testV1&router.name=time&create-collection.numShards=1&action=CREATEROUTEDALIAS&create-collection.router.field=foo_s&wt=xml&create-collection.replicationFactor=2
and sendToOCPQueue=true
[junit4] 2> 350661 INFO
(OverseerCollectionConfigSetProcessor-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_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> 350665 INFO
(OverseerThreadFactory-410-thread-3-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
testV1_2018-02-03_21
[junit4] 2> 350843 INFO
(OverseerStateUpdate-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testV1_2018-02-03_21",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testV1_2018-02-03_21_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46424/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 350859 INFO
(OverseerStateUpdate-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testV1_2018-02-03_21",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testV1_2018-02-03_21_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:36461/solr";,
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 351085 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=_default&newCollection=true&collection=testV1_2018-02-03_21&version=2&replicaType=NRT&property.routedAliasName=testV1&coreNodeName=core_node3&name=testV1_2018-02-03_21_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin
[junit4] 2> 351101 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 351111 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=_default&newCollection=true&collection=testV1_2018-02-03_21&version=2&replicaType=NRT&property.routedAliasName=testV1&coreNodeName=core_node4&name=testV1_2018-02-03_21_shard1_replica_n2&action=CREATE&numShards=1&shard=shard1&wt=javabin
[junit4] 2> 351112 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 351278 INFO
(zkCallback-293-thread-1-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 351278 INFO
(zkCallback-293-thread-2-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 351278 INFO
(zkCallback-291-thread-1-processing-n:127.0.0.1:46424_solr)
[n:127.0.0.1:46424_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 351279 INFO
(zkCallback-291-thread-2-processing-n:127.0.0.1:46424_solr)
[n:127.0.0.1:46424_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 352188 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.RequestParams conf resource
params.json loaded . version : 1
[junit4] 2> 352188 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.RequestParams request params
refreshed to version 1
[junit4] 2> 352189 WARN (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../contrib/extraction/lib
filtered by .*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../contrib/extraction/lib
[junit4] 2> 352189 WARN (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../dist
filtered by solr-cell-\d.*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../dist
[junit4] 2> 352189 WARN (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../contrib/clustering/lib
filtered by .*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../contrib/clustering/lib
[junit4] 2> 352189 WARN (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../dist
filtered by solr-clustering-\d.*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../dist
[junit4] 2> 352189 WARN (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../contrib/langid/lib
filtered by .*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../contrib/langid/lib
[junit4] 2> 352189 WARN (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../dist
filtered by solr-langid-\d.*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../dist
[junit4] 2> 352190 WARN (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../contrib/velocity/lib
filtered by .*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../contrib/velocity/lib
[junit4] 2> 352190 WARN (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../dist
filtered by solr-velocity-\d.*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2/../../../../dist
[junit4] 2> 352204 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.RequestParams conf resource
params.json loaded . version : 1
[junit4] 2> 352204 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.RequestParams request params
refreshed to version 1
[junit4] 2> 352205 WARN (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../contrib/extraction/lib
filtered by .*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 352205 WARN (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../dist
filtered by solr-cell-\d.*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../dist
[junit4] 2> 352211 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.3.0
[junit4] 2> 352218 WARN (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../contrib/clustering/lib
filtered by .*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 352218 WARN (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../dist
filtered by solr-clustering-\d.*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../dist
[junit4] 2> 352218 WARN (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../contrib/langid/lib
filtered by .*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 352218 WARN (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../dist
filtered by solr-langid-\d.*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../dist
[junit4] 2> 352218 WARN (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../contrib/velocity/lib
filtered by .*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 352218 WARN (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files
from
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../dist
filtered by solr-velocity-\d.*\.jar to classpath:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1/../../../../dist
[junit4] 2> 352240 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 7.3.0
[junit4] 2> 352299 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.s.IndexSchema
[testV1_2018-02-03_21_shard1_replica_n2] Schema name=default-config
[junit4] 2> 352328 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.s.IndexSchema
[testV1_2018-02-03_21_shard1_replica_n1] Schema name=default-config
[junit4] 2> 352972 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema
default-config/1.6 with uniqueid field id
[junit4] 2> 352975 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema
default-config/1.6 with uniqueid field id
[junit4] 2> 353101 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.CoreContainer Creating
SolrCore 'testV1_2018-02-03_21_shard1_replica_n1' using configuration from
collection testV1_2018-02-03_21, trusted=true
[junit4] 2> 353102 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_46424.solr.core.testV1_2018-02-03_21.shard1.replica_n1'
(registry 'solr.core.testV1_2018-02-03_21.shard1.replica_n1') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@2469f8e9
[junit4] 2> 353102 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 353102 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrCore
[[testV1_2018-02-03_21_shard1_replica_n1] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/testV1_2018-02-03_21_shard1_replica_n1],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node1/./testV1_2018-02-03_21_shard1_replica_n1/data/]
[junit4] 2> 353143 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.CoreContainer Creating
SolrCore 'testV1_2018-02-03_21_shard1_replica_n2' using configuration from
collection testV1_2018-02-03_21, trusted=true
[junit4] 2> 353144 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_36461.solr.core.testV1_2018-02-03_21.shard1.replica_n2'
(registry 'solr.core.testV1_2018-02-03_21.shard1.replica_n2') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@2469f8e9
[junit4] 2> 353144 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 353144 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrCore
[[testV1_2018-02-03_21_shard1_replica_n2] ] Opening new SolrCore at
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/testV1_2018-02-03_21_shard1_replica_n2],
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J1/temp/solr.cloud.CreateRoutedAliasTest_D99E747A7D538712-001/tempDir-001/node2/./testV1_2018-02-03_21_shard1_replica_n2/data/]
[junit4] 2> 353158 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.r.XSLTResponseWriter
xsltCacheLifetimeSeconds=5
[junit4] 2> 353174 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.r.XSLTResponseWriter
xsltCacheLifetimeSeconds=5
[junit4] 2> 353360 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 353360 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 353378 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: if uncommited for 15000ms;
[junit4] 2> 353378 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 353380 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@1235adf8[testV1_2018-02-03_21_shard1_replica_n1] main]
[junit4] 2> 353393 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/_default
[junit4] 2> 353393 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded
null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/_default
[junit4] 2> 353393 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating
ZooKeeper watch for the managed schema at /configs/_default/managed-schema
[junit4] 2> 353394 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current
schema version 1 is already the latest
[junit4] 2> 353394 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent
Initializing spell checkers
[junit4] 2> 353395 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_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> 353396 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 353405 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 353405 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.u.UpdateLog Initializing
UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 353407 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1591416565784379392
[junit4] 2> 353409 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.u.CommitTracker Hard
AutoCommit: if uncommited for 15000ms;
[junit4] 2> 353409 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 353409 INFO
(searcherExecutor-415-thread-1-processing-n:127.0.0.1:46424_solr
x:testV1_2018-02-03_21_shard1_replica_n1 s:shard1 c:testV1_2018-02-03_21
r:core_node3) [n:127.0.0.1:46424_solr c:testV1_2018-02-03_21 s:shard1
r:core_node3 x:testV1_2018-02-03_21_shard1_replica_n1]
o.a.s.c.QuerySenderListener QuerySenderListener sending requests to
Searcher@1235adf8[testV1_2018-02-03_21_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 353409 INFO
(searcherExecutor-415-thread-1-processing-n:127.0.0.1:46424_solr
x:testV1_2018-02-03_21_shard1_replica_n1 s:shard1 c:testV1_2018-02-03_21
r:core_node3) [n:127.0.0.1:46424_solr c:testV1_2018-02-03_21 s:shard1
r:core_node3 x:testV1_2018-02-03_21_shard1_replica_n1]
o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 353409 INFO
(searcherExecutor-415-thread-1-processing-n:127.0.0.1:46424_solr
x:testV1_2018-02-03_21_shard1_replica_n1 s:shard1 c:testV1_2018-02-03_21
r:core_node3) [n:127.0.0.1:46424_solr c:testV1_2018-02-03_21 s:shard1
r:core_node3 x:testV1_2018-02-03_21_shard1_replica_n1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 353451 INFO
(searcherExecutor-415-thread-1-processing-n:127.0.0.1:46424_solr
x:testV1_2018-02-03_21_shard1_replica_n1 s:shard1 c:testV1_2018-02-03_21
r:core_node3) [n:127.0.0.1:46424_solr c:testV1_2018-02-03_21 s:shard1
r:core_node3 x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SolrCore
[testV1_2018-02-03_21_shard1_replica_n1] Registered new searcher
Searcher@1235adf8[testV1_2018-02-03_21_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 353452 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening
[Searcher@2228e2f4[testV1_2018-02-03_21_shard1_replica_n2] main]
[junit4] 2> 353453 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.r.ManagedResourceStorage
Configured ZooKeeperStorageIO with znodeBase: /configs/_default
[junit4] 2> 353454 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded
null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/_default
[junit4] 2> 353466 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.s.ZkIndexSchemaReader Creating
ZooKeeper watch for the managed schema at /configs/_default/managed-schema
[junit4] 2> 353467 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.s.ZkIndexSchemaReader Current
schema version 1 is already the latest
[junit4] 2> 353468 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.h.c.SpellCheckComponent
Initializing spell checkers
[junit4] 2> 353469 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] 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> 353484 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits
will be reserved for 10000ms.
[junit4] 2> 353484 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max
version in index or recent updates, using new clock 1591416565865119744
[junit4] 2> 353484 INFO
(zkCallback-293-thread-3-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 353484 INFO
(zkCallback-293-thread-2-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 353485 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Waiting until we see more replicas up for shard shard1: total=2 found=1
timeoutin=9999ms
[junit4] 2> 353486 INFO
(zkCallback-291-thread-1-processing-n:127.0.0.1:46424_solr)
[n:127.0.0.1:46424_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 353498 INFO
(searcherExecutor-416-thread-1-processing-n:127.0.0.1:36461_solr
x:testV1_2018-02-03_21_shard1_replica_n2 s:shard1 c:testV1_2018-02-03_21
r:core_node4) [n:127.0.0.1:36461_solr c:testV1_2018-02-03_21 s:shard1
r:core_node4 x:testV1_2018-02-03_21_shard1_replica_n2]
o.a.s.c.QuerySenderListener QuerySenderListener sending requests to
Searcher@2228e2f4[testV1_2018-02-03_21_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 353498 INFO
(searcherExecutor-416-thread-1-processing-n:127.0.0.1:36461_solr
x:testV1_2018-02-03_21_shard1_replica_n2 s:shard1 c:testV1_2018-02-03_21
r:core_node4) [n:127.0.0.1:36461_solr c:testV1_2018-02-03_21 s:shard1
r:core_node4 x:testV1_2018-02-03_21_shard1_replica_n2]
o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 353499 INFO
(searcherExecutor-416-thread-1-processing-n:127.0.0.1:36461_solr
x:testV1_2018-02-03_21_shard1_replica_n2 s:shard1 c:testV1_2018-02-03_21
r:core_node4) [n:127.0.0.1:36461_solr c:testV1_2018-02-03_21 s:shard1
r:core_node4 x:testV1_2018-02-03_21_shard1_replica_n2]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 353525 INFO
(zkCallback-291-thread-3-processing-n:127.0.0.1:46424_solr)
[n:127.0.0.1:46424_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 353527 INFO
(searcherExecutor-416-thread-1-processing-n:127.0.0.1:36461_solr
x:testV1_2018-02-03_21_shard1_replica_n2 s:shard1 c:testV1_2018-02-03_21
r:core_node4) [n:127.0.0.1:36461_solr c:testV1_2018-02-03_21 s:shard1
r:core_node4 x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.SolrCore
[testV1_2018-02-03_21_shard1_replica_n2] Registered new searcher
Searcher@2228e2f4[testV1_2018-02-03_21_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 353986 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 353986 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I
may be the new leader - try and sync
[junit4] 2> 353986 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:46424/solr/testV1_2018-02-03_21_shard1_replica_n1/
[junit4] 2> 353986 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.u.PeerSync PeerSync:
core=testV1_2018-02-03_21_shard1_replica_n1 url=http://127.0.0.1:46424/solr
START
replicas=[http://127.0.0.1:36461/solr/testV1_2018-02-03_21_shard1_replica_n2/]
nUpdates=100
[junit4] 2> 354009 INFO (qtp1866480658-2109) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.c.S.Request
[testV1_2018-02-03_21_shard1_replica_n2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=21
[junit4] 2> 354297 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.u.PeerSync PeerSync:
core=testV1_2018-02-03_21_shard1_replica_n1 url=http://127.0.0.1:46424/solr
DONE. We have no versions. sync failed.
[junit4] 2> 354297 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.SyncStrategy Leader's attempt
to sync with shard failed, moving to the next candidate
[junit4] 2> 354297 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext We
failed sync, but we have no versions - we can't sync in that case - we were
active before, so become leader anyway
[junit4] 2> 354297 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext
Found all replicas participating in election, clear LIR
[junit4] 2> 354331 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I
am the new leader:
http://127.0.0.1:46424/solr/testV1_2018-02-03_21_shard1_replica_n1/ shard1
[junit4] 2> 354492 INFO
(zkCallback-293-thread-1-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 354492 INFO
(zkCallback-293-thread-2-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 354500 INFO
(zkCallback-291-thread-1-processing-n:127.0.0.1:46424_solr)
[n:127.0.0.1:46424_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 354508 INFO
(zkCallback-291-thread-3-processing-n:127.0.0.1:46424_solr)
[n:127.0.0.1:46424_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 354542 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.c.ZkController I am the leader,
no recovery necessary
[junit4] 2> 354543 INFO (qtp790497909-2106) [n:127.0.0.1:46424_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node3
x:testV1_2018-02-03_21_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=_default&newCollection=true&collection=testV1_2018-02-03_21&version=2&replicaType=NRT&property.routedAliasName=testV1&coreNodeName=core_node3&name=testV1_2018-02-03_21_shard1_replica_n1&action=CREATE&numShards=1&shard=shard1&wt=javabin}
status=0 QTime=3458
[junit4] 2> 354558 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr
c:testV1_2018-02-03_21 s:shard1 r:core_node4
x:testV1_2018-02-03_21_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin]
webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=_default&newCollection=true&collection=testV1_2018-02-03_21&version=2&replicaType=NRT&property.routedAliasName=testV1&coreNodeName=core_node4&name=testV1_2018-02-03_21_shard1_replica_n2&action=CREATE&numShards=1&shard=shard1&wt=javabin}
status=0 QTime=3447
[junit4] 2> 354558 INFO
(OverseerThreadFactory-410-thread-3-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_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> 354695 INFO
(zkCallback-293-thread-1-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 354695 INFO
(zkCallback-291-thread-3-processing-n:127.0.0.1:46424_solr)
[n:127.0.0.1:46424_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 354695 INFO
(zkCallback-291-thread-1-processing-n:127.0.0.1:46424_solr)
[n:127.0.0.1:46424_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 354695 INFO
(zkCallback-293-thread-2-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/testV1_2018-02-03_21/state.json] for collection
[testV1_2018-02-03_21] has occurred - updating... (live nodes size: [2])
[junit4] 2> 355663 INFO (qtp790497909-2100) [n:127.0.0.1:46424_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={router.interval=%2B30MINUTE&router.start=2018-02-03T21:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&name=testV1&router.name=time&create-collection.numShards=1&action=CREATEROUTEDALIAS&create-collection.router.field=foo_s&wt=xml&create-collection.replicationFactor=2}
status=0 QTime=5087
[junit4] 2> 355665 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with
params
action=CLUSTERSTATUS&collection=testV1_2018-02-03_21&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 355691 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=CLUSTERSTATUS&collection=testV1_2018-02-03_21&wt=javabin&version=2}
status=0 QTime=26
[junit4] 2> 355693 INFO (qtp1866480658-2105) [n:127.0.0.1:36461_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with
params
action=CLUSTERSTATUS&collection=testV1_2018-02-03_21&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 355693 INFO (qtp1866480658-2105) [n:127.0.0.1:36461_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={action=CLUSTERSTATUS&collection=testV1_2018-02-03_21&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 355694 INFO
(TEST-CreateRoutedAliasTest.testV1-seed#[D99E747A7D538712]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testV1
[junit4] 2> 355801 INFO
(TEST-CreateRoutedAliasTest.testNegativeFutureFails-seed#[D99E747A7D538712]) [
] o.a.s.SolrTestCaseJ4 ###Starting testNegativeFutureFails
[junit4] 2> 355804 INFO (qtp1866480658-2105) [n:127.0.0.1:36461_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with
params action=CLUSTERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 355805 INFO (qtp1866480658-2105) [n:127.0.0.1:36461_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> 355862 INFO
(TEST-CreateRoutedAliasTest.testNegativeFutureFails-seed#[D99E747A7D538712]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 355866 INFO (qtp790497909-2100) [n:127.0.0.1:46424_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=testV1_2018-02-03_21&action=DELETE&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 355911 INFO
(OverseerCollectionConfigSetProcessor-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 355911 INFO
(OverseerThreadFactory-410-thread-4-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Executing Collection Cmd :
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 356092 INFO (qtp790497909-2104) [n:127.0.0.1:46424_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.testV1_2018-02-03_21.shard1.replica_n1, tag=null
[junit4] 2> 356092 INFO (qtp790497909-2104) [n:127.0.0.1:46424_solr ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@41e2e560: rootName =
solr_46424, domain = solr.core.testV1_2018-02-03_21.shard1.replica_n1, service
url = null, agent id = null] for registry
solr.core.testV1_2018-02-03_21.shard1.replica_n1 /
com.codahale.metrics.MetricRegistry@204f47bc
[junit4] 2> 356092 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.testV1_2018-02-03_21.shard1.replica_n2, tag=null
[junit4] 2> 356092 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@5fe2d53b: rootName =
solr_36461, domain = solr.core.testV1_2018-02-03_21.shard1.replica_n2, service
url = null, agent id = null] for registry
solr.core.testV1_2018-02-03_21.shard1.replica_n2 /
com.codahale.metrics.MetricRegistry@16d862c3
[junit4] 2> 356163 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.c.SolrCore [testV1_2018-02-03_21_shard1_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@9aad0f0
[junit4] 2> 356163 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.testV1_2018-02-03_21.shard1.replica_n2, tag=162189552
[junit4] 2> 356163 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.testV1_2018-02-03_21.shard1.leader, tag=162189552
[junit4] 2> 356186 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader
parent node, won't remove previous leader registration.
[junit4] 2> 356186 INFO (qtp1866480658-2111) [n:127.0.0.1:36461_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=testV1_2018-02-03_21_shard1_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=94
[junit4] 2> 356204 INFO (qtp790497909-2104) [n:127.0.0.1:46424_solr ]
o.a.s.c.SolrCore [testV1_2018-02-03_21_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@9c2a63
[junit4] 2> 356204 INFO (qtp790497909-2104) [n:127.0.0.1:46424_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.testV1_2018-02-03_21.shard1.replica_n1, tag=10234467
[junit4] 2> 356204 INFO (qtp790497909-2104) [n:127.0.0.1:46424_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.testV1_2018-02-03_21.shard1.leader, tag=10234467
[junit4] 2> 356261 INFO (qtp790497909-2104) [n:127.0.0.1:46424_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=testV1_2018-02-03_21_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=228
[junit4] 2> 356262 WARN
(zkCallback-293-thread-2-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.LeaderElector Our node is no longer in
line to be leader
[junit4] 2> 357006 INFO (qtp790497909-2100) [n:127.0.0.1:46424_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=testV1_2018-02-03_21&action=DELETE&wt=javabin&version=2} status=0
QTime=1139
[junit4] 2> 357007 INFO (qtp790497909-2100) [n:127.0.0.1:46424_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :createroutedalias with
params
router.interval=%2B30MINUTE&router.start=2018-01-15T00:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&router.max-future-ms=-60000&name=testNegativeFutureFails&router.name=time&create-collection.numShards=1&action=CREATEROUTEDALIAS&wt=json
and sendToOCPQueue=true
[junit4] 2> 357021 INFO
(OverseerCollectionConfigSetProcessor-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 357022 ERROR
(OverseerThreadFactory-410-thread-5-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Collection: testNegativeFutureFails operation: createroutedalias
failed:org.apache.solr.common.SolrException: router.max-future-ms must be >= 0
[junit4] 2> at
org.apache.solr.cloud.api.collections.TimeRoutedAlias.<init>(TimeRoutedAlias.java:153)
[junit4] 2> at
org.apache.solr.cloud.api.collections.CreateAliasCmd.callCreateRoutedAlias(CreateAliasCmd.java:116)
[junit4] 2> at
org.apache.solr.cloud.api.collections.CreateAliasCmd.call(CreateAliasCmd.java:65)
[junit4] 2> at
org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:254)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 357059 INFO (qtp790497909-2100) [n:127.0.0.1:46424_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={router.interval=%2B30MINUTE&router.start=2018-01-15T00:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&router.max-future-ms=-60000&name=testNegativeFutureFails&router.name=time&create-collection.numShards=1&action=CREATEROUTEDALIAS&wt=json}
status=400 QTime=51
[junit4] 2> 357059 INFO
(TEST-CreateRoutedAliasTest.testNegativeFutureFails-seed#[D99E747A7D538712]) [
] o.a.s.SolrTestCaseJ4 ###Ending testNegativeFutureFails
[junit4] 2> 357175 INFO
(TEST-CreateRoutedAliasTest.testUnParseableFutureFails-seed#[D99E747A7D538712])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testUnParseableFutureFails
[junit4] 2> 357177 INFO
(TEST-CreateRoutedAliasTest.testUnParseableFutureFails-seed#[D99E747A7D538712])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 357191 INFO (qtp790497909-2100) [n:127.0.0.1:46424_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :createroutedalias with
params
router.interval=%2B30MINUTE&router.start=2018-01-15T00:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&router.max-future-ms=SixtyThousandMilliseconds&name=testAlias&router.name=time&create-collection.numShards=1&action=CREATEROUTEDALIAS&wt=json
and sendToOCPQueue=true
[junit4] 2> 357193 ERROR
(OverseerThreadFactory-410-thread-5-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler
Collection: testAlias operation: createroutedalias
failed:org.apache.solr.common.SolrException: For input string:
"SixtyThousandMilliseconds"
[junit4] 2> at
org.apache.solr.common.params.SolrParams.getLong(SolrParams.java:212)
[junit4] 2> at
org.apache.solr.cloud.api.collections.TimeRoutedAlias.<init>(TimeRoutedAlias.java:140)
[junit4] 2> at
org.apache.solr.cloud.api.collections.CreateAliasCmd.callCreateRoutedAlias(CreateAliasCmd.java:116)
[junit4] 2> at
org.apache.solr.cloud.api.collections.CreateAliasCmd.call(CreateAliasCmd.java:65)
[junit4] 2> at
org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:254)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.lang.NumberFormatException: For input string:
"SixtyThousandMilliseconds"
[junit4] 2> at
java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
[junit4] 2> at java.lang.Long.parseLong(Long.java:589)
[junit4] 2> at java.lang.Long.parseLong(Long.java:631)
[junit4] 2> at
org.apache.solr.common.params.SolrParams.getLong(SolrParams.java:210)
[junit4] 2> ... 9 more
[junit4] 2>
[junit4] 2> 357221 INFO (qtp790497909-2100) [n:127.0.0.1:46424_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={router.interval=%2B30MINUTE&router.start=2018-01-15T00:00:00Z&create-collection.collection.configName=_default&router.field=evt_dt&router.max-future-ms=SixtyThousandMilliseconds&name=testAlias&router.name=time&create-collection.numShards=1&action=CREATEROUTEDALIAS&wt=json}
status=400 QTime=30
[junit4] 2> 357222 INFO
(TEST-CreateRoutedAliasTest.testUnParseableFutureFails-seed#[D99E747A7D538712])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testUnParseableFutureFails
[junit4] 2> 357259 INFO
(OverseerCollectionConfigSetProcessor-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 357283 INFO
(OverseerCollectionConfigSetProcessor-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000010 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 357387 INFO
(TEST-CreateRoutedAliasTest.testV2-seed#[D99E747A7D538712]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testV2
[junit4] 2> 357389 INFO
(TEST-CreateRoutedAliasTest.testV2-seed#[D99E747A7D538712]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 357476 INFO
(OverseerThreadFactory-410-thread-5-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
testV2_2018-02-03
[junit4] 2> 357477 WARN
(OverseerThreadFactory-410-thread-5-processing-n:127.0.0.1:36461_solr)
[n:127.0.0.1:36461_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number
of replicas of 2 on collection testV2_2018-02-03 is higher than the number of
Solr instances currently live or live and part of your createNodeSet(1). It's
unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 357740 INFO
(OverseerStateUpdate-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testV2_2018-02-03",
[junit4] 2> "shard":"foo",
[junit4] 2> "core":"testV2_2018-02-03_foo_replica_t1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46424/solr";,
[junit4] 2> "type":"TLOG",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 357751 INFO
(OverseerStateUpdate-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testV2_2018-02-03",
[junit4] 2> "shard":"foo",
[junit4] 2> "core":"testV2_2018-02-03_foo_replica_p2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46424/solr";,
[junit4] 2> "type":"PULL",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 357753 INFO
(OverseerStateUpdate-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testV2_2018-02-03",
[junit4] 2> "shard":"bar",
[junit4] 2> "core":"testV2_2018-02-03_bar_replica_t4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46424/solr";,
[junit4] 2> "type":"TLOG",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 357780 INFO
(OverseerStateUpdate-72059592923021318-127.0.0.1:36461_solr-n_0000000000)
[n:127.0.0.1:36461_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testV2_2018-02-03",
[junit4] 2> "shard":"bar",
[junit4] 2> "core":"testV2_2018-02-03_bar_replica_p7",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46424/solr";,
[junit4] 2> "type":"PULL",
[junit4
[...truncated too long message...]
d=2110, name=qtp1866480658-2110, state=TIMED_WAITING,
group=TGRP-CreateRoutedAliasTest]
[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> 2) Thread[id=2111, name=qtp1866480658-2111,
state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
[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 03, 2018 9:26:13 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=2110, name=qtp1866480658-2110,
state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
[junit4] 2> 2) Thread[id=2111, name=qtp1866480658-2111,
state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
[junit4] 2> Feb 03, 2018 9:26:16 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be
terminated:
[junit4] 2> 1) Thread[id=2110, name=qtp1866480658-2110,
state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
[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> 2) Thread[id=2111, name=qtp1866480658-2111,
state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
[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),
sim=RandomSimilarity(queryNorm=false): {}, locale=no, timezone=Asia/Karachi
[junit4] 2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation
1.8.0_152 (64-bit)/cpus=4,threads=3,free=208682696,total=490209280
[junit4] 2> NOTE: All tests run in this JVM: [BaseCdcrDistributedZkTest,
StressHdfsTest, DocumentBuilderTest, LargeFieldTest,
TestSubQueryTransformerDistrib, AtomicUpdateProcessorFactoryTest,
TestSystemCollAutoCreate, TestLegacyField, TestCloudSearcherWarming,
ScriptEngineTest, CSVRequestHandlerTest, BitVectorTest, SliceStateTest,
TestMaxTokenLenTokenizer, TestReloadDeadlock, CreateRoutedAliasTest]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=CreateRoutedAliasTest -Dtests.seed=D99E747A7D538712
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=no
-Dtests.timezone=Asia/Karachi -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J1 | CreateRoutedAliasTest (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 2 threads leaked from SUITE
scope at org.apache.solr.cloud.CreateRoutedAliasTest:
[junit4] > 1) Thread[id=2110, name=qtp1866480658-2110,
state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
[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] > 2) Thread[id=2111, name=qtp1866480658-2111,
state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
[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([D99E747A7D538712]:0)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=2110, name=qtp1866480658-2110,
state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
[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] > 2) Thread[id=2111, name=qtp1866480658-2111,
state=TIMED_WAITING, group=TGRP-CreateRoutedAliasTest]
[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([D99E747A7D538712]:0)
[junit4] Completed [58/774 (1!)] on J1 in 77.19s, 9 tests, 2 errors <<<
FAILURES!
[...truncated 50456 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
