You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/05/03 14:36:37 UTC
[JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_92) - Build # 253 -
Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/253/
Java: 64bit/jdk1.8.0_92 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.util.TestSolrCLIRunExample
Error Message:
ObjectTracker found 4 object(s) that were not released!!! [MDCAwareThreadPoolExecutor, TransactionLog, MockDirectoryWrapper, MockDirectoryWrapper]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 4 object(s) that were not released!!! [MDCAwareThreadPoolExecutor, TransactionLog, MockDirectoryWrapper, MockDirectoryWrapper]
at __randomizedtesting.SeedInfo.seed([E270D44FE1EB8737]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:228)
at sun.reflect.GeneratedMethodAccessor42.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.util.TestSolrCLIRunExample
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.util.TestSolrCLIRunExample: 1) Thread[id=13177, name=searcherExecutor-5384-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.util.TestSolrCLIRunExample:
1) Thread[id=13177, name=searcherExecutor-5384-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([E270D44FE1EB8737]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.util.TestSolrCLIRunExample
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=13177, name=searcherExecutor-5384-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=13177, name=searcherExecutor-5384-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([E270D44FE1EB8737]:0)
Build Log:
[...truncated 12404 lines...]
[junit4] Suite: org.apache.solr.util.TestSolrCLIRunExample
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/init-core-data-001
[junit4] 2> 1920979 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testInteractiveSolrCloudExample
[junit4] 2> 1921006 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1921007 INFO (Thread-4157) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1921007 INFO (Thread-4157) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1921106 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.ZkTestServer start zk server on port:38638
[junit4] 2> 1921107 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1921107 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1921109 INFO (zkCallback-2369-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4600ecf0 name:ZooKeeperConnection Watcher:127.0.0.1:38638 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1921109 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1921109 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1921109 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 1921112 INFO (jetty-launcher-2368-thread-1) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1921113 INFO (jetty-launcher-2368-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4787c00d{/solr,null,AVAILABLE}
[junit4] 2> 1921113 INFO (jetty-launcher-2368-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@4143b399{HTTP/1.1}{127.0.0.1:45174}
[junit4] 2> 1921113 INFO (jetty-launcher-2368-thread-1) [ ] o.e.j.s.Server Started @1923094ms
[junit4] 2> 1921113 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45174}
[junit4] 2> 1921113 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1921113 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1'
[junit4] 2> 1921114 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1921114 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1921114 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1921114 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1921115 INFO (zkCallback-2370-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f9ddc82 name:ZooKeeperConnection Watcher:127.0.0.1:38638/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1921115 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1921115 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1921115 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1921119 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1
[junit4] 2> 1921120 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 1011488689
[junit4] 2> 1921120 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1]
[junit4] 2> 1921120 WARN (jetty-launcher-2368-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/lib
[junit4] 2> 1921120 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1921121 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 1921121 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1921121 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1921121 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38638/solr
[junit4] 2> 1921121 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1921121 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1921121 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1921122 INFO (zkCallback-2373-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@60527e60 name:ZooKeeperConnection Watcher:127.0.0.1:38638 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1921122 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1921123 INFO (jetty-launcher-2368-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1921124 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$6@5536446a
[junit4] 2> 1921124 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1921125 INFO (zkCallback-2374-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@17fd1d51 name:ZooKeeperConnection Watcher:127.0.0.1:38638/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1921125 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1921125 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1921126 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1921127 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1921128 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1921129 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1921130 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1921130 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1921130 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1921131 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1921131 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1921131 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/localhost:45174_solr
[junit4] 2> 1921132 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/localhost:45174_solr
[junit4] 2> 1921132 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1921132 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1921133 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1921133 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95831905524580355-localhost:45174_solr-n_0000000000
[junit4] 2> 1921133 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader localhost:45174_solr
[junit4] 2> 1921133 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1921134 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.Overseer Overseer (id=95831905524580355-localhost:45174_solr-n_0000000000) starting
[junit4] 2> 1921134 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1921136 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1921137 INFO (OverseerCollectionConfigSetProcessor-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 1921137 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1921137 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1921144 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1921144 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1921144 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1
[junit4] 2> 1921144 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1921145 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1
[junit4] 2> 1921145 INFO (jetty-launcher-2368-thread-1) [n:localhost:45174_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1921145 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1921145 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1921146 INFO (zkCallback-2375-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10d96889 name:ZooKeeperConnection Watcher:127.0.0.1:38638 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1921146 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1921146 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1921178 INFO (qtp354656497-13048) [n:localhost:45174_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=26
[junit4] 2> 1921182 INFO (qtp354656497-13049) [n:localhost:45174_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 1921183 INFO (qtp354656497-13049) [n:localhost:45174_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=1
[junit4] 2> 1921183 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1921184 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1921184 INFO (zkCallback-2378-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@162d20b8 name:ZooKeeperConnection Watcher:127.0.0.1:38638/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1921184 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1921185 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1921185 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1921188 INFO (qtp354656497-13048) [n:localhost:45174_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 1921188 INFO (qtp354656497-13048) [n:localhost:45174_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 1921196 INFO (qtp354656497-13050) [n:localhost:45174_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=6
[junit4] 2> 1921204 INFO (qtp354656497-13048) [n:localhost:45174_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
[junit4] 2> 1921205 INFO (qtp354656497-13048) [n:localhost:45174_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=0
[junit4] 2> 1921205 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1921205 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1921206 INFO (zkCallback-2380-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@41fef287 name:ZooKeeperConnection Watcher:127.0.0.1:38638/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1921206 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1921207 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1921207 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1921209 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/protwords.txt
[junit4] 2> 1921210 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/solrconfig.xml
[junit4] 2> 1921211 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/stopwords.txt
[junit4] 2> 1921211 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ar.txt
[junit4] 2> 1921212 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_el.txt
[junit4] 2> 1921213 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ja.txt
[junit4] 2> 1921213 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_gl.txt
[junit4] 2> 1921214 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_fr.txt
[junit4] 2> 1921215 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fr.txt
[junit4] 2> 1921216 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_nl.txt
[junit4] 2> 1921217 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_cz.txt
[junit4] 2> 1921217 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ca.txt
[junit4] 2> 1921218 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_de.txt
[junit4] 2> 1921220 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ga.txt
[junit4] 2> 1921220 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_da.txt
[junit4] 2> 1921221 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_no.txt
[junit4] 2> 1921222 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_es.txt
[junit4] 2> 1921222 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_sv.txt
[junit4] 2> 1921223 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fi.txt
[junit4] 2> 1921224 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hu.txt
[junit4] 2> 1921225 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_bg.txt
[junit4] 2> 1921225 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_fa.txt
[junit4] 2> 1921226 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_it.txt
[junit4] 2> 1921227 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_eu.txt
[junit4] 2> 1921228 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ro.txt
[junit4] 2> 1921228 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ga.txt
[junit4] 2> 1921229 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stemdict_nl.txt
[junit4] 2> 1921230 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hy.txt
[junit4] 2> 1921230 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/contractions_ca.txt
[junit4] 2> 1921231 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stoptags_ja.txt
[junit4] 2> 1921232 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_lv.txt
[junit4] 2> 1921233 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/userdict_ja.txt
[junit4] 2> 1921233 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_tr.txt
[junit4] 2> 1921234 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_it.txt
[junit4] 2> 1921235 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_ru.txt
[junit4] 2> 1921236 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_th.txt
[junit4] 2> 1921236 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/hyphenations_ga.txt
[junit4] 2> 1921237 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_en.txt
[junit4] 2> 1921237 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_hi.txt
[junit4] 2> 1921238 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_pt.txt
[junit4] 2> 1921239 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/lang/stopwords_id.txt
[junit4] 2> 1921240 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/synonyms.txt
[junit4] 2> 1921240 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/elevate.xml
[junit4] 2> 1921240 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/currency.xml
[junit4] 2> 1921241 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/params.json
[junit4] 2> 1921242 INFO (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[E270D44FE1EB8737]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/testCloudExamplePrompt/managed-schema
[junit4] 2> 1921244 INFO (qtp354656497-13050) [n:localhost:45174_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
[junit4] 2> 1921244 INFO (qtp354656497-13050) [n:localhost:45174_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
[junit4] 2> 1921246 INFO (qtp354656497-13049) [n:localhost:45174_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&maxShardsPerNode=4&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt&action=CREATE&numShards=2&wt=json and sendToOCPQueue=true
[junit4] 2> 1921247 INFO (OverseerCollectionConfigSetProcessor-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 1921247 INFO (OverseerThreadFactory-5344-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 1921247 INFO (OverseerThreadFactory-5344-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.OverseerCollectionMessageHandler Create collection testCloudExamplePrompt
[junit4] 2> 1921247 WARN (OverseerThreadFactory-5344-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.OverseerCollectionMessageHandler Specified replicationFactor of 2 on collection testCloudExamplePrompt is higher than or equal to 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> 1921248 INFO (OverseerThreadFactory-5344-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.OverseerCollectionMessageHandler creating collections conf node /collections/testCloudExamplePrompt
[junit4] 2> 1921248 INFO (OverseerThreadFactory-5344-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/testCloudExamplePrompt
[junit4] 2> 1921248 INFO (zkCallback-2380-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1921248 INFO (zkCallback-2374-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1921249 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"testCloudExamplePrompt",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"testCloudExamplePrompt",
[junit4] 2> "numShards":"2",
[junit4] 2> "maxShardsPerNode":"4",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1921249 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testCloudExamplePrompt
[junit4] 2> 1921249 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testCloudExamplePrompt/state.json
[junit4] 2> 1921349 INFO (OverseerThreadFactory-5344-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating SolrCores for new collection testCloudExamplePrompt, shardNames [shard1, shard2] , replicationFactor : 2
[junit4] 2> 1921349 INFO (OverseerThreadFactory-5344-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testCloudExamplePrompt_shard1_replica1 as part of shard shard1 of collection testCloudExamplePrompt on localhost:45174_solr
[junit4] 2> 1921350 INFO (OverseerThreadFactory-5344-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testCloudExamplePrompt_shard1_replica2 as part of shard shard1 of collection testCloudExamplePrompt on localhost:45174_solr
[junit4] 2> 1921350 INFO (OverseerThreadFactory-5344-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testCloudExamplePrompt_shard2_replica1 as part of shard shard2 of collection testCloudExamplePrompt on localhost:45174_solr
[junit4] 2> 1921350 INFO (OverseerThreadFactory-5344-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testCloudExamplePrompt_shard2_replica2 as part of shard shard2 of collection testCloudExamplePrompt on localhost:45174_solr
[junit4] 2> 1921351 INFO (qtp354656497-13050) [n:localhost:45174_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard1_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 1921353 INFO (qtp354656497-13050) [n:localhost:45174_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard1_replica1, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard1}
[junit4] 2> 1921353 INFO (qtp354656497-13048) [n:localhost:45174_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard2_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 1921353 INFO (qtp354656497-13086) [n:localhost:45174_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard1_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
[junit4] 2> 1921353 INFO (qtp354656497-13048) [n:localhost:45174_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard2_replica2, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard2}
[junit4] 2> 1921353 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1921353 INFO (qtp354656497-13086) [n:localhost:45174_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard1_replica2, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard1}
[junit4] 2> 1921354 INFO (qtp354656497-13087) [n:localhost:45174_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt_shard2_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
[junit4] 2> 1921354 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1921354 INFO (qtp354656497-13087) [n:localhost:45174_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testCloudExamplePrompt_shard2_replica1, collection.configName=testCloudExamplePrompt, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testCloudExamplePrompt, numShards=2, dataDir=data/, shard=shard2}
[junit4] 2> 1921354 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1921354 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.c.ZkStateReader addZkWatch [testCloudExamplePrompt]
[junit4] 2> 1921355 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.c.ZkStateReader Add data for [testCloudExamplePrompt] ver [0]
[junit4] 2> 1921355 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1921355 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1921355 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:45174/solr",
[junit4] 2> "node_name":"localhost:45174_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1921355 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1921355 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1921355 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1921355 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1921355 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1921355 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:45174/solr",
[junit4] 2> "node_name":"localhost:45174_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1921355 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Registering watch for collection testCloudExamplePrompt
[junit4] 2> 1921355 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1921356 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:45174/solr",
[junit4] 2> "node_name":"localhost:45174_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1921356 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:45174/solr",
[junit4] 2> "node_name":"localhost:45174_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1921357 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:45174/solr",
[junit4] 2> "node_name":"localhost:45174_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1921359 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard2_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:45174/solr",
[junit4] 2> "node_name":"localhost:45174_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1921359 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:45174/solr",
[junit4] 2> "node_name":"localhost:45174_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1921359 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"testCloudExamplePrompt_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://localhost:45174/solr",
[junit4] 2> "node_name":"localhost:45174_solr",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCloudExamplePrompt",
[junit4] 2> "operation":"state"}
[junit4] 2> 1921460 INFO (OverseerStateUpdate-95831905524580355-localhost:45174_solr-n_0000000000) [n:localhost:45174_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testCloudExamplePrompt/state.json version: 0
[junit4] 2> 1921461 INFO (zkCallback-2374-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json] for collection [testCloudExamplePrompt] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1921461 INFO (zkCallback-2374-thread-1-processing-n:localhost:45174_solr) [n:localhost:45174_solr ] o.a.s.c.c.ZkStateReader Updating data for [testCloudExamplePrompt] from [0] to [1]
[junit4] 2> 1922355 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard1_replica1
[junit4] 2> 1922355 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1922355 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard2_replica2
[junit4] 2> 1922355 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1922355 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard2_replica1
[junit4] 2> 1922355 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1922355 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1922355 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1922355 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1922355 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1922356 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testCloudExamplePrompt_shard1_replica2
[junit4] 2> 1922356 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Check for collection zkNode:testCloudExamplePrompt
[junit4] 2> 1922356 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloudExamplePrompt] specified config exists in ZooKeeper
[junit4] 2> 1922356 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1922356 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1922356 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloudExamplePrompt] specified config exists in ZooKeeper
[junit4] 2> 1922356 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1922356 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testCloudExamplePrompt]
[junit4] 2> 1922356 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1'
[junit4] 2> 1922356 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2'
[junit4] 2> 1922356 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloudExamplePrompt] specified config exists in ZooKeeper
[junit4] 2> 1922356 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.c.ZkStateReader path=[/collections/testCloudExamplePrompt] [configName]=[testCloudExamplePrompt] specified config exists in ZooKeeper
[junit4] 2> 1922356 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1922356 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1'
[junit4] 2> 1922356 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1922356 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2'
[junit4] 2> 1922356 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1922357 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1922356 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1922357 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1922357 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1922357 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1922358 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1922358 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1922358 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1922358 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1922361 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1922362 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1922362 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 1922362 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1922362 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1922362 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 1922362 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 1922363 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 1922363 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1922363 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1922363 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1922363 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1922363 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 1922363 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig current version of requestparams : 0
[junit4] 2> 1922364 WARN (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/extraction/lib
[junit4] 2> 1922364 WARN (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/extraction/lib
[junit4] 2> 1922364 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 1922364 WARN (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist
[junit4] 2> 1922364 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Adding specified lib dirs to ClassLoader
[junit4] 2> 1922364 WARN (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist
[junit4] 2> 1922364 WARN (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/extraction/lib
[junit4] 2> 1922364 WARN (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/clustering/lib
[junit4] 2> 1922364 WARN (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/clustering/lib
[junit4] 2> 1922364 WARN (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist
[junit4] 2> 1922365 WARN (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist
[junit4] 2> 1922365 WARN (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist
[junit4] 2> 1922365 WARN (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/langid/lib
[junit4] 2> 1922364 WARN (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/extraction/lib
[junit4] 2> 1922365 WARN (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist
[junit4] 2> 1922365 WARN (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/clustering/lib
[junit4] 2> 1922365 WARN (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist
[junit4] 2> 1922365 WARN (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist
[junit4] 2> 1922365 WARN (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/langid/lib
[junit4] 2> 1922366 WARN (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/clustering/lib
[junit4] 2> 1922365 WARN (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../contrib/velocity/lib
[junit4] 2> 1922366 WARN (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist
[junit4] 2> 1922366 WARN (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica2/../../../../dist
[junit4] 2> 1922366 WARN (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/langid/lib
[junit4] 2> 1922366 WARN (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist
[junit4] 2> 1922366 WARN (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist
[junit4] 2> 1922366 WARN (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/langid/lib
[junit4] 2> 1922366 WARN (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../contrib/velocity/lib
[junit4] 2> 1922366 WARN (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../contrib/velocity/lib
[junit4] 2> 1922367 WARN (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard1_replica1/../../../../dist
[junit4] 2> 1922367 WARN (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica2/../../../../dist
[junit4] 2> 1922366 WARN (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist
[junit4] 2> 1922367 WARN (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../contrib/velocity/lib
[junit4] 2> 1922368 WARN (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J1/temp/solr.util.TestSolrCLIRunExample_E270D44FE1EB8737-001/tempDir-002/node1/testCloudExamplePrompt_shard2_replica1/../../../../dist
[junit4] 2> 1922376 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.1
[junit4] 2> 1922377 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.1
[junit4] 2> 1922377 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.1
[junit4] 2> 1922380 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.1
[junit4] 2> 1922401 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1922405 INFO (qtp354656497-13050) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica1] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard1_replica1] Schema name=example-data-driven-schema
[junit4] 2> 1922407 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1922410 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1922412 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1922414 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard2_replica2] Schema name=example-data-driven-schema
[junit4] 2> 1922416 INFO (qtp354656497-13086) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard1 x:testCloudExamplePrompt_shard1_replica2] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard1_replica2] Schema name=example-data-driven-schema
[junit4] 2> 1922417 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.IndexSchema [testCloudExamplePrompt_shard2_replica1] Schema name=example-data-driven-schema
[junit4] 2> 1922598 INFO (qtp354656497-13048) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica2] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1922602 INFO (qtp354656497-13087) [n:localhost:45174_solr c:testCloudExamplePrompt s:shard2 x:testCloudExamplePrompt_shard2_replica1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2>
[...truncated too long message...]
readPoolExecutor.java:1067)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> May 03, 2016 12:36:31 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=13177, name=searcherExecutor-5384-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample]
[junit4] 2> May 03, 2016 12:36:34 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=13177, name=searcherExecutor-5384-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {}, docValues:{}, sim=DefaultSimilarity, locale=lt-LT, timezone=Chile/Continental
[junit4] 2> NOTE: Linux 4.2.0-35-generic amd64/Oracle Corporation 1.8.0_92 (64-bit)/cpus=12,threads=2,free=118355904,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [SolrCloudExampleTest, TermsComponentTest, ZkCLITest, TestSortByMinMaxFunction, TestExceedMaxTermLength, UpdateRequestProcessorFactoryTest, TestSolrCoreProperties, TestDFRSimilarityFactory, SchemaVersionSpecificBehaviorTest, ZkControllerTest, ConcurrentDeleteAndCreateCollectionTest, OpenExchangeRatesOrgProviderTest, TestLFUCache, TestQuerySenderNoQuery, HdfsChaosMonkeyNothingIsSafeTest, TestCustomSort, DirectoryFactoryTest, ConvertedLegacyTest, OverseerTaskQueueTest, TestStressUserVersions, SortByFunctionTest, TestNonDefinedSimilarityFactory, TestBinaryResponseWriter, TestConfigSetsAPIZkFailure, TestDefaultStatsCache, TimeZoneUtilsTest, WordBreakSolrSpellCheckerTest, DistributedExpandComponentTest, TestUseDocValuesAsStored, TestWordDelimiterFilterFactory, AnalyticsQueryTest, AsyncMigrateRouteKeyTest, SpellPossibilityIteratorTest, TestUpdate, DistributedQueryComponentCustomSortTest, TestQuerySenderListener, FacetPivotSmallTest, TestNRTOpen, TestFiltering, TestPartialUpdateDeduplication, TestCursorMarkWithoutUniqueKey, MultiThreadedOCPTest, TestDownShardTolerantSearch, SimplePostToolTest, ReplicationFactorTest, SimpleMLTQParserTest, TestJsonFacets, TestEmbeddedSolrServerConstructors, TestDynamicFieldResource, TestCopyFieldCollectionResource, CoreAdminRequestStatusTest, DOMUtilTest, SolrIndexSplitterTest, SparseHLLTest, TestFastLRUCache, OverseerCollectionConfigSetProcessorTest, AliasIntegrationTest, SpatialRPTFieldTypeTest, TestPivotHelperCode, DistributedTermsComponentTest, RuleEngineTest, TestRuleBasedAuthorizationPlugin, DocExpirationUpdateProcessorFactoryTest, SpellingQueryConverterTest, SpatialFilterTest, SoftAutoCommitTest, CloudExitableDirectoryReaderTest, DistribJoinFromCollectionTest, TestSchemaVersionResource, MultiTermTest, TestExactStatsCache, AddSchemaFieldsUpdateProcessorFactoryTest, VMParamsZkACLAndCredentialsProvidersTest, TestLMJelinekMercerSimilarityFactory, TestMacros, MBeansHandlerTest, TestStressVersions, TestHashPartitioner, HdfsRestartWhileUpdatingTest, CollectionTooManyReplicasTest, PreAnalyzedUpdateProcessorTest, TestConfigSetProperties, TestRestManager, TestManagedSchemaFieldResource, ShardRoutingTest, OpenCloseCoreStressTest, TestSolrQueryParserResource, BlockCacheTest, SolrIndexConfigTest, BlockJoinFacetRandomTest, TestReplicationHandlerBackup, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, SyncSliceTest, OverseerTest, ClusterStateUpdateTest, TestRandomFaceting, ShardRoutingCustomTest, TestDistributedSearch, TestSolrIndexConfig, TestMergePolicyConfig, TestBinaryField, OutputWriterTest, TestCharFilters, TestXIncludeConfig, EchoParamsTest, TestPerFieldSimilarity, TestJmxMonitoredMap, ChaosMonkeyNothingIsSafeTest, ConfigSetsAPITest, CustomCollectionTest, DistribDocExpirationUpdateProcessorTest, LeaderFailoverAfterPartitionTest, LeaderInitiatedRecoveryOnCommitTest, RollingRestartTest, ShardSplitTest, TestSolrConfigHandlerConcurrent, SuggestComponentContextFilterQueryTest, TestExpandComponent, TestTrackingShardHandlerFactory, TestFieldResource, TestRemoveLastDynamicCopyField, TestManagedSynonymFilterFactory, TestCloudManagedSchemaConcurrent, ResponseHeaderTest, ConjunctionSolrSpellCheckerTest, TestFileDictionaryLookup, TestFreeTextSuggestions, BlockDirectoryTest, HdfsDirectoryTest, AnalysisErrorHandlingTest, DataDrivenBlockJoinTest, VersionInfoTest, AtomicUpdatesTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, ParsingFieldUpdateProcessorsTest, UUIDUpdateProcessorFallbackTest, DistanceUnitsTest, TestObjectReleaseTracker, TestSolrCLIRunExample]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCLIRunExample -Dtests.seed=E270D44FE1EB8737 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lt-LT -Dtests.timezone=Chile/Continental -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | TestSolrCLIRunExample (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 4 object(s) that were not released!!! [MDCAwareThreadPoolExecutor, TransactionLog, MockDirectoryWrapper, MockDirectoryWrapper]
[junit4] > at __randomizedtesting.SeedInfo.seed([E270D44FE1EB8737]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:228)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.util.TestSolrCLIRunExample:
[junit4] > 1) Thread[id=13177, name=searcherExecutor-5384-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([E270D44FE1EB8737]:0)Throwable #3: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=13177, name=searcherExecutor-5384-thread-1, state=WAITING, group=TGRP-TestSolrCLIRunExample]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([E270D44FE1EB8737]:0)
[junit4] Completed [572/572 (1!)] on J1 in 162.78s, 3 tests, 1 failure, 2 errors, 1 skipped <<< FAILURES!
[...truncated 11 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:750: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:694: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/common-build.xml:534: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1477: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1033: There were test failures: 572 suites (7 ignored), 2293 tests, 3 suite-level errors, 84 ignored (39 assumptions) [seed: E270D44FE1EB8737]
Total time: 56 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-5.5-Linux (64bit/jdk1.8.0_92) - Build # 254 -
Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.5-Linux/254/
Java: 64bit/jdk1.8.0_92 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.schema.TestManagedSchemaAPI.test
Error Message:
Error from server at http://127.0.0.1:46446/solr/testschemaapi_shard1_replica2: ERROR: [doc=2] unknown field 'myNewField1'
Stack Trace:
org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from server at http://127.0.0.1:46446/solr/testschemaapi_shard1_replica2: ERROR: [doc=2] unknown field 'myNewField1'
at __randomizedtesting.SeedInfo.seed([3F8BA8832F5BC510:B7DF975981A7A8E8]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:632)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:981)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
at org.apache.solr.schema.TestManagedSchemaAPI.testAddFieldAndDocument(TestManagedSchemaAPI.java:101)
at org.apache.solr.schema.TestManagedSchemaAPI.test(TestManagedSchemaAPI.java:69)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46446/solr/testschemaapi_shard1_replica2: ERROR: [doc=2] unknown field 'myNewField1'
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
at org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:607)
at org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:604)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
... 1 more
Build Log:
[...truncated 12248 lines...]
[junit4] Suite: org.apache.solr.schema.TestManagedSchemaAPI
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/init-core-data-001
[junit4] 2> 1849110 INFO (SUITE-TestManagedSchemaAPI-seed#[3F8BA8832F5BC510]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1849116 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1849116 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1849117 INFO (Thread-5789) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1849117 INFO (Thread-5789) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1849217 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.ZkTestServer start zk server on port:34958
[junit4] 2> 1849217 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1849217 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1849219 INFO (zkCallback-2002-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c122370 name:ZooKeeperConnection Watcher:127.0.0.1:34958 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1849219 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1849219 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1849220 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr/solr.xml
[junit4] 2> 1849223 INFO (jetty-launcher-2001-thread-1) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1849223 INFO (jetty-launcher-2001-thread-2) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1849224 INFO (jetty-launcher-2001-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3541b78d{/solr,null,AVAILABLE}
[junit4] 2> 1849224 INFO (jetty-launcher-2001-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@9d6fb6f{/solr,null,AVAILABLE}
[junit4] 2> 1849224 INFO (jetty-launcher-2001-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@66264c18{HTTP/1.1}{127.0.0.1:46446}
[junit4] 2> 1849225 INFO (jetty-launcher-2001-thread-2) [ ] o.e.j.s.Server Started @1850778ms
[junit4] 2> 1849225 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46446}
[junit4] 2> 1849225 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1849225 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2'
[junit4] 2> 1849225 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1849225 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1849225 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1849239 INFO (jetty-launcher-2001-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@1d6ca0b4{HTTP/1.1}{127.0.0.1:42303}
[junit4] 2> 1849239 INFO (jetty-launcher-2001-thread-1) [ ] o.e.j.s.Server Started @1850792ms
[junit4] 2> 1849239 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1849239 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42303}
[junit4] 2> 1849239 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 1849239 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1'
[junit4] 2> 1849239 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1849239 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1849240 INFO (zkCallback-2003-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@63ff531f name:ZooKeeperConnection Watcher:127.0.0.1:34958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1849240 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1849240 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1849240 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1849240 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1849257 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1849290 INFO (zkCallback-2004-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@63bcc147 name:ZooKeeperConnection Watcher:127.0.0.1:34958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1849290 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1849291 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1849291 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1849296 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/.
[junit4] 2> 1849296 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.CoreContainer New CoreContainer 186295391
[junit4] 2> 1849296 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2]
[junit4] 2> 1849297 WARN (jetty-launcher-2001-thread-2) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/lib
[junit4] 2> 1849297 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1849298 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1849299 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1849299 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1849299 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34958/solr
[junit4] 2> 1849299 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1849299 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1849299 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1849300 INFO (zkCallback-2007-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@43a774fc name:ZooKeeperConnection Watcher:127.0.0.1:34958 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1849304 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1849304 INFO (jetty-launcher-2001-thread-2) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1849306 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/.
[junit4] 2> 1849307 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.CoreContainer New CoreContainer 175968139
[junit4] 2> 1849307 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1]
[junit4] 2> 1849307 WARN (jetty-launcher-2001-thread-1) [ ] o.a.s.c.CoreContainer Couldn't add files from /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/lib to classpath: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/lib
[junit4] 2> 1849307 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1849308 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1849308 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1849308 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1849308 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34958/solr
[junit4] 2> 1849308 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1849309 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1849316 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1849316 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$6@20f2bdc4
[junit4] 2> 1849317 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1849323 INFO (zkCallback-2010-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@444fd402 name:ZooKeeperConnection Watcher:127.0.0.1:34958 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1849323 INFO (zkCallback-2011-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7d0bd229 name:ZooKeeperConnection Watcher:127.0.0.1:34958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1849324 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1849324 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1849324 INFO (jetty-launcher-2001-thread-1) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1849325 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1849326 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$6@1ce19a82
[junit4] 2> 1849327 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1849327 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1849328 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1849328 INFO (zkCallback-2012-thread-1-processing-n:127.0.0.1:42303_solr) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@60298b2 name:ZooKeeperConnection Watcher:127.0.0.1:34958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1849329 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1849331 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1849332 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1849334 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1849335 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 1849336 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1849336 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1849337 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1849338 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46446_solr
[junit4] 2> 1849338 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:46446_solr
[junit4] 2> 1849339 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1849342 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1849343 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1849344 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95832670126735364-127.0.0.1:46446_solr-n_0000000000
[junit4] 2> 1849344 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46446_solr
[junit4] 2> 1849344 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1849345 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.Overseer Overseer (id=95832670126735364-127.0.0.1:46446_solr-n_0000000000) starting
[junit4] 2> 1849347 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1849350 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42303_solr
[junit4] 2> 1849351 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1849351 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1849352 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1849353 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:42303_solr
[junit4] 2> 1849353 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1849354 INFO (zkCallback-2011-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1])
[junit4] 2> 1849355 INFO (OverseerCollectionConfigSetProcessor-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 1849355 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1849355 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95832670126735366-127.0.0.1:42303_solr-n_0000000001
[junit4] 2> 1849355 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95832670126735364-127.0.0.1:46446_solr-n_0000000000 to know if I could be the leader
[junit4] 2> 1849355 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1849369 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1849369 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1849370 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/.
[junit4] 2> 1849370 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1849371 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1849372 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1849372 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/.
[junit4] 2> 1849372 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions
[junit4] 2> 1849379 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1849379 INFO (jetty-launcher-2001-thread-1) [n:127.0.0.1:42303_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1849381 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.s.SolrDispatchFilter user.dir=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1849381 INFO (jetty-launcher-2001-thread-2) [n:127.0.0.1:46446_solr ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1849381 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1849383 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1849389 INFO (zkCallback-2013-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@70db8b33 name:ZooKeeperConnection Watcher:127.0.0.1:34958 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1849389 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1849390 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1849391 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1849391 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1849392 INFO (zkCallback-2015-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@288e5e7b name:ZooKeeperConnection Watcher:127.0.0.1:34958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1849392 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1849392 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1849393 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1849394 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/managed-schema
[junit4] 2> 1849395 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1849396 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1849396 INFO (zkCallback-2016-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@71673f46 name:ZooKeeperConnection Watcher:127.0.0.1:34958/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1849396 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1849397 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1849397 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1849400 INFO (qtp334741895-14209) [n:127.0.0.1:42303_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf1&name=testschemaapi&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1849402 INFO (OverseerCollectionConfigSetProcessor-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.OverseerTaskProcessor Overseer Collection Message Handler: Get the message id:/overseer/collection-queue-work/qn-0000000000 message:{
[junit4] 2> "name":"testschemaapi",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 1849402 INFO (OverseerThreadFactory-7245-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.OverseerCollectionMessageHandler OverseerCollectionMessageHandler.processMessage : create , {
[junit4] 2> "name":"testschemaapi",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"}
[junit4] 2> 1849404 INFO (OverseerThreadFactory-7245-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.OverseerCollectionMessageHandler Create collection testschemaapi
[junit4] 2> 1849405 INFO (OverseerThreadFactory-7245-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.OverseerCollectionMessageHandler creating collections conf node /collections/testschemaapi
[junit4] 2> 1849406 INFO (OverseerThreadFactory-7245-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.SolrZkClient makePath: /collections/testschemaapi
[junit4] 2> 1849415 INFO (zkCallback-2012-thread-1-processing-n:127.0.0.1:42303_solr) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1849415 INFO (zkCallback-2016-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1849416 INFO (zkCallback-2011-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1849416 INFO (zkCallback-2011-thread-2-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader A collections change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections], has occurred - updating...
[junit4] 2> 1849424 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"testschemaapi",
[junit4] 2> "fromApi":"true",
[junit4] 2> "replicationFactor":"2",
[junit4] 2> "collection.configName":"conf1",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1849424 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testschemaapi
[junit4] 2> 1849425 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testschemaapi/state.json
[junit4] 2> 1849529 INFO (OverseerThreadFactory-7245-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating SolrCores for new collection testschemaapi, shardNames [shard1] , replicationFactor : 2
[junit4] 2> 1849529 INFO (OverseerThreadFactory-7245-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testschemaapi_shard1_replica1 as part of shard shard1 of collection testschemaapi on 127.0.0.1:42303_solr
[junit4] 2> 1849530 INFO (OverseerThreadFactory-7245-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.OverseerCollectionMessageHandler Creating core testschemaapi_shard1_replica2 as part of shard shard1 of collection testschemaapi on 127.0.0.1:46446_solr
[junit4] 2> 1849531 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=testschemaapi_shard1_replica1&action=CREATE&numShards=1&collection=testschemaapi&shard=shard1&wt=javabin&version=2
[junit4] 2> 1849531 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testschemaapi_shard1_replica1, collection.configName=conf1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testschemaapi, numShards=1, dataDir=data/, shard=shard1}
[junit4] 2> 1849532 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=testschemaapi_shard1_replica2&action=CREATE&numShards=1&collection=testschemaapi&shard=shard1&wt=javabin&version=2
[junit4] 2> 1849532 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1849533 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=testschemaapi_shard1_replica2, collection.configName=conf1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=testschemaapi, numShards=1, dataDir=data/, shard=shard1}
[junit4] 2> 1849533 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Registering watch for collection testschemaapi
[junit4] 2> 1849533 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader addZkWatch [testschemaapi]
[junit4] 2> 1849534 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader Add data for [testschemaapi] ver [0]
[junit4] 2> 1849534 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1849534 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testschemaapi_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:42303/solr",
[junit4] 2> "node_name":"127.0.0.1:42303_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1849535 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1849535 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testschemaapi_shard1_replica1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:42303/solr",
[junit4] 2> "node_name":"127.0.0.1:42303_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"}
[junit4] 2> 1849536 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Registering watch for collection testschemaapi
[junit4] 2> 1849536 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader addZkWatch [testschemaapi]
[junit4] 2> 1849537 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader Add data for [testschemaapi] ver [0]
[junit4] 2> 1849537 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1849537 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testschemaapi_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46446/solr",
[junit4] 2> "node_name":"127.0.0.1:46446_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1849537 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testschemaapi_shard1_replica2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46446/solr",
[junit4] 2> "node_name":"127.0.0.1:46446_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"}
[junit4] 2> 1849639 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testschemaapi/state.json version: 0
[junit4] 2> 1849640 INFO (zkCallback-2011-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1849640 INFO (zkCallback-2011-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [0] to [1]
[junit4] 2> 1849640 INFO (zkCallback-2012-thread-1-processing-n:127.0.0.1:42303_solr) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1849640 INFO (zkCallback-2012-thread-1-processing-n:127.0.0.1:42303_solr) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [0] to [1]
[junit4] 2> 1850541 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController waiting to find shard id in clusterstate for testschemaapi_shard1_replica2
[junit4] 2> 1850541 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController waiting to find shard id in clusterstate for testschemaapi_shard1_replica1
[junit4] 2> 1850541 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Check for collection zkNode:testschemaapi
[junit4] 2> 1850542 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Check for collection zkNode:testschemaapi
[junit4] 2> 1850542 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1850542 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 1850542 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1850542 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 1850542 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader path=[/collections/testschemaapi] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 1850543 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader path=[/collections/testschemaapi] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 1850543 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/testschemaapi_shard1_replica1'
[junit4] 2> 1850543 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/testschemaapi_shard1_replica2'
[junit4] 2> 1850543 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1850543 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1850543 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1850543 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1850544 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1850544 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1850547 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1850547 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1850550 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.1
[junit4] 2> 1850550 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.1
[junit4] 2> 1850574 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1850574 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1850577 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSchema [testschemaapi_shard1_replica1] Schema name=minimal
[junit4] 2> 1850577 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSchema [testschemaapi_shard1_replica2] Schema name=minimal
[junit4] 2> 1850580 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1850581 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1850582 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1850582 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'testschemaapi_shard1_replica1' using configuration from collection testschemaapi
[junit4] 2> 1850582 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1850582 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore [[testschemaapi_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/testschemaapi_shard1_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/./testschemaapi_shard1_replica1/data/]
[junit4] 2> 1850583 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore JMX monitoring not detected for core: testschemaapi_shard1_replica1
[junit4] 2> 1850583 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0
[junit4] 2> 1850583 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/./testschemaapi_shard1_replica1/data
[junit4] 2> 1850583 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 x:testschemaapi_shard1_replica2] o.a.s.c.CoreContainer Creating SolrCore 'testschemaapi_shard1_replica2' using configuration from collection testschemaapi
[junit4] 2> 1850583 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/./testschemaapi_shard1_replica1/data/index/
[junit4] 2> 1850583 WARN (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore [testschemaapi_shard1_replica1] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/./testschemaapi_shard1_replica1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1850583 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1850584 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore [[testschemaapi_shard1_replica2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/testschemaapi_shard1_replica2], dataDir=[/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/./testschemaapi_shard1_replica2/data/]
[junit4] 2> 1850584 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/./testschemaapi_shard1_replica1/data/index
[junit4] 2> 1850585 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore JMX monitoring not detected for core: testschemaapi_shard1_replica2
[junit4] 2> 1850585 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/./testschemaapi_shard1_replica2/data
[junit4] 2> 1850585 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore New index directory detected: old=null new=/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/./testschemaapi_shard1_replica2/data/index/
[junit4] 2> 1850585 WARN (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore [testschemaapi_shard1_replica2] Solr index directory '/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/./testschemaapi_shard1_replica2/data/index' doesn't exist. Creating new index...
[junit4] 2> 1850586 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.CachingDirectoryFactory return new directory for /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/./testschemaapi_shard1_replica2/data/index
[junit4] 2> 1850586 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5070d8b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fb5c101),segFN=segments_1,generation=1}
[junit4] 2> 1850586 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1850587 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cc8f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c4fdcee),segFN=segments_1,generation=1}
[junit4] 2> 1850587 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1850587 INFO (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica1) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core testschemaapi_shard1_replica1 in /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/./testschemaapi_shard1_replica1/data/
[junit4] 2> 1850587 INFO (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica2) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore Looking for old index directories to cleanup for core testschemaapi_shard1_replica2 in /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/./testschemaapi_shard1_replica2/data/
[junit4] 2> 1850587 WARN (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica1) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node1/./testschemaapi_shard1_replica1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1850587 WARN (OldIndexDirectoryCleanupThreadForCore-testschemaapi_shard1_replica2) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001/tempDir-001/node2/./testschemaapi_shard1_replica2/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1850588 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1850588 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1850589 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1850589 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1850589 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1850590 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1850591 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1850591 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1850591 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1850592 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1850592 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1850592 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1850592 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1850592 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1850593 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,/update/csv,/update/json/docs,/admin/luke,/select,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1850593 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1850594 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1850594 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1850604 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1850604 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1850605 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cc8f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c4fdcee),segFN=segments_1,generation=1}
[junit4] 2> 1850605 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1850605 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.s.SolrIndexSearcher Opening Searcher@130729ef[testschemaapi_shard1_replica2] main
[junit4] 2> 1850605 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 1850605 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.c.ZkStateReader path=[/collections/testschemaapi] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 1850605 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1850606 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1850606 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1850606 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1850606 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1850606 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1850606 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1850606 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 1850606 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Added new OnReconnect listener ZkIndexSchemaReader: /configs/conf1/managed-schema, uniqueCoreId: testschemaapi_shard1_replica2:1019784849876712
[junit4] 2> 1850606 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1850607 INFO (searcherExecutor-7251-thread-1-processing-n:127.0.0.1:46446_solr x:testschemaapi_shard1_replica2 s:shard1 c:testschemaapi r:core_node2) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SolrCore [testschemaapi_shard1_replica2] Registered new searcher Searcher@130729ef[testschemaapi_shard1_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1850607 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 1850607 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 1850607 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 1850607 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1533322723589095424
[junit4] 2> 1850609 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1533322723589095424
[junit4] 2> 1850609 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 1850610 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.CoreContainer registering core: testschemaapi_shard1_replica2
[junit4] 2> 1850610 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController Register replica - core:testschemaapi_shard1_replica2 address:http://127.0.0.1:46446/solr collection:testschemaapi shard:shard1
[junit4] 2> 1850610 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.c.SolrZkClient makePath: /collections/testschemaapi/leader_elect/shard1/election
[junit4] 2> 1850611 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.LeaderElector Joined leadership election with path: /collections/testschemaapi/leader_elect/shard1/election/95832670126735364-core_node2-n_0000000000
[junit4] 2> 1850612 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=10000
[junit4] 2> 1850612 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 1850613 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi"} current state version: 0
[junit4] 2> 1850624 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1850624 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1850625 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5070d8b7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@fb5c101),segFN=segments_1,generation=1}
[junit4] 2> 1850625 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1850625 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening Searcher@5ea76604[testschemaapi_shard1_replica1] main
[junit4] 2> 1850625 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/testschemaapi]
[junit4] 2> 1850625 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.c.ZkStateReader path=[/collections/testschemaapi] [configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 1850625 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1850627 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1850627 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1850627 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1850627 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1850627 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1850627 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1850627 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 1850628 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Added new OnReconnect listener ZkIndexSchemaReader: /configs/conf1/managed-schema, uniqueCoreId: testschemaapi_shard1_replica1:1019784848572095
[junit4] 2> 1850628 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1850628 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 1850628 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 1850628 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 1850628 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1533322723611115520
[junit4] 2> 1850630 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1533322723611115520
[junit4] 2> 1850630 INFO (searcherExecutor-7250-thread-1-processing-n:127.0.0.1:42303_solr x:testschemaapi_shard1_replica1 s:shard1 c:testschemaapi r:core_node1) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.SolrCore [testschemaapi_shard1_replica1] Registered new searcher Searcher@5ea76604[testschemaapi_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1850630 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 1850631 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.CoreContainer registering core: testschemaapi_shard1_replica1
[junit4] 2> 1850631 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController Register replica - core:testschemaapi_shard1_replica1 address:http://127.0.0.1:42303/solr collection:testschemaapi shard:shard1
[junit4] 2> 1850635 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/testschemaapi/leader_elect/shard1/election/95832670126735366-core_node1-n_0000000001
[junit4] 2> 1850636 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.LeaderElector Watching path /collections/testschemaapi/leader_elect/shard1/election/95832670126735364-core_node2-n_0000000000 to know if I could be the leader
[junit4] 2> 1850723 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testschemaapi/state.json version: 1
[junit4] 2> 1850729 INFO (zkCallback-2011-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1850729 INFO (zkCallback-2011-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [1] to [2]
[junit4] 2> 1850729 INFO (zkCallback-2012-thread-1-processing-n:127.0.0.1:42303_solr) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1850731 INFO (zkCallback-2012-thread-1-processing-n:127.0.0.1:42303_solr) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [1] to [2]
[junit4] 2> 1851113 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1851113 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1851113 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46446/solr/testschemaapi_shard1_replica2/
[junit4] 2> 1851113 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.PeerSync PeerSync: core=testschemaapi_shard1_replica2 url=http://127.0.0.1:46446/solr START replicas=[http://127.0.0.1:42303/solr/testschemaapi_shard1_replica1/] nUpdates=100
[junit4] 2> 1851113 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.u.PeerSync PeerSync: core=testschemaapi_shard1_replica2 url=http://127.0.0.1:46446/solr DONE. We have no versions. sync failed.
[junit4] 2> 1851113 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1851113 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] 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> 1851115 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.c.SolrZkClient makePath: /collections/testschemaapi/leaders/shard1
[junit4] 2> 1851116 INFO (qtp334741895-14213) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.S.Request [testschemaapi_shard1_replica1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1851117 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testschemaapi/leaders/shard1/leader after winning as /collections/testschemaapi/leader_elect/shard1/election/95832670126735364-core_node2-n_0000000000
[junit4] 2> 1851119 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:46446/solr/testschemaapi_shard1_replica2/ shard1
[junit4] 2> 1851119 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "base_url":"http://127.0.0.1:46446/solr",
[junit4] 2> "core":"testschemaapi_shard1_replica2",
[junit4] 2> "state":"active"} current state version: 0
[junit4] 2> 1851220 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testschemaapi/state.json version: 2
[junit4] 2> 1851220 INFO (zkCallback-2011-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1851220 INFO (zkCallback-2012-thread-1-processing-n:127.0.0.1:42303_solr) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1851220 INFO (zkCallback-2012-thread-1-processing-n:127.0.0.1:42303_solr) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [2] to [3]
[junit4] 2> 1851220 INFO (zkCallback-2011-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [2] to [3]
[junit4] 2> 1851269 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController We are http://127.0.0.1:46446/solr/testschemaapi_shard1_replica2/ and leader is http://127.0.0.1:46446/solr/testschemaapi_shard1_replica2/
[junit4] 2> 1851269 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController No LogReplay needed for core=testschemaapi_shard1_replica2 baseURL=http://127.0.0.1:46446/solr
[junit4] 2> 1851270 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1851270 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.c.ZkController publishing state=active
[junit4] 2> 1851270 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"testschemaapi_shard1_replica2",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46446/solr",
[junit4] 2> "node_name":"127.0.0.1:46446_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1851271 INFO (qtp1236260016-14210) [n:127.0.0.1:46446_solr c:testschemaapi s:shard1 r:core_node2 x:testschemaapi_shard1_replica2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=testschemaapi_shard1_replica2&action=CREATE&numShards=1&collection=testschemaapi&shard=shard1&wt=javabin&version=2} status=0 QTime=1738
[junit4] 2> 1851271 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testschemaapi_shard1_replica2",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:46446/solr",
[junit4] 2> "node_name":"127.0.0.1:46446_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testschemaapi",
[junit4] 2> "operation":"state"}
[junit4] 2> 1851378 INFO (OverseerStateUpdate-95832670126735364-127.0.0.1:46446_solr-n_0000000000) [n:127.0.0.1:46446_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testschemaapi/state.json version: 3
[junit4] 2> 1851421 INFO (zkCallback-2012-thread-1-processing-n:127.0.0.1:42303_solr) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1851421 INFO (zkCallback-2011-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testschemaapi/state.json] for collection [testschemaapi] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1851422 INFO (zkCallback-2012-thread-1-processing-n:127.0.0.1:42303_solr) [n:127.0.0.1:42303_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [3] to [4]
[junit4] 2> 1851422 INFO (zkCallback-2011-thread-1-processing-n:127.0.0.1:46446_solr) [n:127.0.0.1:46446_solr ] o.a.s.c.c.ZkStateReader Updating data for [testschemaapi] from [3] to [4]
[junit4] 2> 1851651 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController We are http://127.0.0.1:42303/solr/testschemaapi_shard1_replica1/ and leader is http://127.0.0.1:46446/solr/testschemaapi_shard1_replica2/
[junit4] 2> 1851651 INFO (qtp334741895-14211) [n:127.0.0.1:42303_solr c:testschemaapi s:shard1 r:core_node1 x:testschemaapi_shard1_replica1] o.a.s.c.ZkController No LogReplay needed for core=testschemaapi_shard1_replica1 base
[...truncated too long message...]
xception: Unable to reload core [testschemaapi_shard1_replica1]
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:922)
[junit4] 2> at org.apache.solr.core.SolrCore$11.run(SolrCore.java:2603)
[junit4] 2> at org.apache.solr.cloud.ZkController$5.run(ZkController.java:2479)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Could not find config name for collection:testschemaapi
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:820)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:461)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:917)
[junit4] 2> ... 2 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Could not find config name for collection:testschemaapi
[junit4] 2> at org.apache.solr.rest.ManagedResourceStorage.newStorageIO(ManagedResourceStorage.java:101)
[junit4] 2> at org.apache.solr.core.SolrCore.initRestManager(SolrCore.java:2397)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:796)
[junit4] 2> ... 4 more
[junit4] 2> 1860018 INFO (TEST-TestManagedSchemaAPI.test-seed#[3F8BA8832F5BC510]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestManagedSchemaAPI -Dtests.method=test -Dtests.seed=3F8BA8832F5BC510 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-QA -Dtests.timezone=America/Thule -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 10.9s J0 | TestManagedSchemaAPI.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.CloudSolrClient$RouteException: Error from server at http://127.0.0.1:46446/solr/testschemaapi_shard1_replica2: ERROR: [doc=2] unknown field 'myNewField1'
[junit4] > at __randomizedtesting.SeedInfo.seed([3F8BA8832F5BC510:B7DF975981A7A8E8]:0)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:632)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:981)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
[junit4] > at org.apache.solr.schema.TestManagedSchemaAPI.testAddFieldAndDocument(TestManagedSchemaAPI.java:101)
[junit4] > at org.apache.solr.schema.TestManagedSchemaAPI.test(TestManagedSchemaAPI.java:69)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46446/solr/testschemaapi_shard1_replica2: ERROR: [doc=2] unknown field 'myNewField1'
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:607)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:604)
[junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] > at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > ... 1 more
[junit4] 2> 1860031 INFO (SUITE-TestManagedSchemaAPI-seed#[3F8BA8832F5BC510]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestManagedSchemaAPI_3F8BA8832F5BC510-001
[junit4] 2> May 03, 2016 3:48:29 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 3 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {_version_=PostingsFormat(name=Memory doPackFST= false), id=PostingsFormat(name=LuceneVarGapFixedInterval), myNewField=PostingsFormat(name=MockRandom)}, docValues:{}, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=ar-QA, timezone=America/Thule
[junit4] 2> NOTE: Linux 4.2.0-35-generic amd64/Oracle Corporation 1.8.0_92 (64-bit)/cpus=12,threads=1,free=154811568,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestCrossCoreJoin, IndexSchemaTest, CoreAdminCreateDiscoverTest, BasicAuthIntegrationTest, TestRestoreCore, DistributedDebugComponentTest, ParsingFieldUpdateProcessorsTest, TestOnReconnectListenerSupport, CopyFieldTest, JSONWriterTest, ConnectionReuseTest, TestExpandComponent, SystemInfoHandlerTest, PathHierarchyTokenizerFactoryTest, TestInitParams, TestHighlightDedupGrouping, TestSolrXml, IndexSchemaRuntimeFieldTest, TestValueSourceCache, TestLeaderElectionZkExpiry, TestLazyCores, TestHighFrequencyDictionaryFactory, DateFieldTest, DistributedFacetPivotLongTailTest, ReturnFieldsTest, XsltUpdateRequestHandlerTest, TestManagedStopFilterFactory, DistributedVersionInfoTest, AddBlockUpdateTest, TestShortCircuitedRequests, SynonymTokenizerTest, BasicDistributedZkTest, BasicZkTest, TestAtomicUpdateErrorCases, TestSchemaManager, TestConfigSetsAPIExclusivity, HdfsThreadLeakTest, TestSolrQueryParserDefaultOperatorResource, TestFastOutputStream, SyncSliceTest, ChangedSchemaMergeTest, UUIDUpdateProcessorFallbackTest, BufferStoreTest, PolyFieldTest, DistributedSpellCheckComponentTest, TestBinaryField, TestOmitPositions, BigEndianAscendingWordDeserializerTest, TestMiniSolrCloudClusterBase, CheckHdfsIndexTest, CacheHeaderTest, AnalyticsMergeStrategyTest, TestRecoveryHdfs, TestFaceting, DistributedSuggestComponentTest, TestSchemaSimilarityResource, LeaderElectionIntegrationTest, TestLRUCache, TestPhraseSuggestions, InfoHandlerTest, HdfsRecoveryZkTest, TestConfigSetImmutable, TestFoldingMultitermQuery, ResponseLogComponentTest, TestIBSimilarityFactory, RAMDirectoryFactoryTest, CloneFieldUpdateProcessorFactoryTest, CloudMLTQParserTest, TestRTGBase, TestManagedSchemaDynamicFieldResource, OutputWriterTest, DeleteShardTest, TestDynamicLoading, SolrCoreTest, TestDocSet, TestPseudoReturnFields, UUIDFieldTest, TestCustomDocTransformer, CollectionsAPISolrJTest, DistributedQueryElevationComponentTest, MergeStrategyTest, TestRandomCollapseQParserPlugin, AlternateDirectoryTest, ExplicitHLLTest, TestAnalyzedSuggestions, PrimUtilsTest, SuggestComponentContextFilterQueryTest, SimpleCollectionCreateDeleteTest, TestArbitraryIndexDir, AsyncCallRequestStatusResponseTest, TestConfig, ResponseHeaderTest, EnumFieldTest, TestMergePolicyConfig, TestReplicaProperties, UnloadDistributedZkTest, TestReplicationHandler, TestDistributedGrouping, TermVectorComponentDistributedTest, TestRealTimeGet, TestJoin, TestStressVersions, HardAutoCommitTest, TestRangeQuery, TestSolr4Spatial, StatsComponentTest, SpellCheckComponentTest, BasicFunctionalityTest, TestBadConfig, SoftAutoCommitTest, SpellCheckCollatorTest, SuggesterTSTTest, FieldMutatingUpdateProcessorTest, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, DocValuesMultiTest, TestSolrDeletionPolicy1, DebugComponentTest, StandardRequestHandlerTest, FileBasedSpellCheckerTest, MoreLikeThisHandlerTest, TestJmxIntegration, UniqFieldsUpdateProcessorFactoryTest, PingRequestHandlerTest, SolrTestCaseJ4Test, TestSimpleTrackingShardHandler, AsyncMigrateRouteKeyTest, CollectionTooManyReplicasTest, DeleteInactiveReplicaTest, DistribDocExpirationUpdateProcessorTest, LeaderInitiatedRecoveryOnCommitTest, MultiThreadedOCPTest, OverseerStatusTest, OverseerTaskQueueTest, ReplicationFactorTest, RestartWhileUpdatingTest, RollingRestartTest, SSLMigrationTest, ShardSplitTest, TestConfigReload, TestSolrConfigHandlerCloud, TestPivotHelperCode, JavabinLoaderTest, TestIntervalFaceting, TestRestManager, TestCopyFieldCollectionResource, TestDynamicFieldCollectionResource, TestFieldResource, TestManagedSchemaFieldTypeResource, TestBulkSchemaConcurrent, TestManagedSchemaAPI]
[junit4] Completed [519/572 (1!)] on J0 in 11.45s, 1 test, 1 error <<< FAILURES!
[...truncated 172 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:750: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:694: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/build.xml:233: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/solr/common-build.xml:534: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1477: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.5-Linux/lucene/common-build.xml:1033: There were test failures: 572 suites (7 ignored), 2293 tests, 1 error, 84 ignored (39 assumptions) [seed: 3F8BA8832F5BC510]
Total time: 56 minutes 41 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any