Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/810/
1 tests failed. FAILED: org.apache.solr.handler.TestSQLHandler.doTest Error Message: --> http://127.0.0.1:60472/collection1:java.util.concurrent.ExecutionException: java.io.IOException: --> http://127.0.0.1:48489/collection1/: An exception has occurred on the server, refer to server log for details. Stack Trace: java.io.IOException: --> http://127.0.0.1:60472/collection1:java.util.concurrent.ExecutionException: java.io.IOException: --> http://127.0.0.1:48489/collection1/: An exception has occurred on the server, refer to server log for details. at __randomizedtesting.SeedInfo.seed([8DBBA9930FEE5899:2AFF113762554B20]:0) at org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:178) at org.apache.solr.handler.TestSQLHandler.getTuples(TestSQLHandler.java:1317) at org.apache.solr.handler.TestSQLHandler.testBasicSelect(TestSQLHandler.java:232) at org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:96) 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:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1665) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:864) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:900) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:914) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) 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:873) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:775) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:809) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:820) 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:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) 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:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) 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) Build Log: [...truncated 10236 lines...] [junit4] JVM J1: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/temp/junit4-J1-20151002_194259_204.sysout [junit4] >>> JVM J1: stdout (verbatim) ---- [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/heapdumps/java_pid9470.hprof ... [junit4] Heap dump file created [727183481 bytes in 6.999 secs] [junit4] <<< JVM J1: EOF ---- [junit4] JVM J1: stderr was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/temp/junit4-J1-20151002_194259_204.syserr [junit4] >>> JVM J1: stderr (verbatim) ---- [junit4] WARN: Unhandled exception in event serialization. -> java.lang.OutOfMemoryError: GC overhead limit exceeded [junit4] <<< JVM J1: EOF ---- [...truncated 715 lines...] [junit4] Suite: org.apache.solr.handler.TestSQLHandler [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/init-core-data-001 [junit4] 2> 4500734 INFO (SUITE-TestSQLHandler-seed#[8DBBA9930FEE5899]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 4500734 INFO (SUITE-TestSQLHandler-seed#[8DBBA9930FEE5899]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 4500737 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 4500737 INFO (Thread-21486) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4500737 INFO (Thread-21486) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 4500837 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.ZkTestServer start zk server on port:50472 [junit4] 2> 4500838 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4500838 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4500840 INFO (zkCallback-3648-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6172e325 name:ZooKeeperConnection Watcher:127.0.0.1:50472 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4500840 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4500841 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4500841 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 4500843 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4500844 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4500844 INFO (zkCallback-3649-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@33652586 name:ZooKeeperConnection Watcher:127.0.0.1:50472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4500844 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4500845 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4500845 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 4500846 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 4500848 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 4500849 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 4500850 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-sql.xml to /configs/conf1/solrconfig.xml [junit4] 2> 4500850 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4500853 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema-sql.xml to /configs/conf1/schema.xml [junit4] 2> 4500853 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 4500855 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4500855 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4500856 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 4500856 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 4500858 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 4500858 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 4500860 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 4500860 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 4500861 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 4500861 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4500863 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 4500863 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4500865 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4500865 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4500866 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 4500867 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4500868 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 4500868 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 4501198 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1 [junit4] 2> 4501200 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 4501204 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1306e8b1{/,null,AVAILABLE} [junit4] 2> 4501208 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.e.j.s.ServerConnector Started ServerConnector@28e1a66f{HTTP/1.1}{127.0.0.1:42176} [junit4] 2> 4501208 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.e.j.s.Server Started @4504712ms [junit4] 2> 4501208 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/tempDir-001/control/data, hostContext=/, hostPort=42176, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores} [junit4] 2> 4501208 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 4501208 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/' [junit4] 2> 4501240 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4501241 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4501242 INFO (zkCallback-3650-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3268d912 name:ZooKeeperConnection Watcher:127.0.0.1:50472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4501242 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4501242 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4501244 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4501244 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/solr.xml [junit4] 2> 4501248 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores [junit4] 2> 4501248 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.CoreContainer New CoreContainer 1995737110 [junit4] 2> 4501248 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/] [junit4] 2> 4501248 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/lib [junit4] 2> 4501248 WARN (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/lib). [junit4] 2> 4501255 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] 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> 4501256 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4501256 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4501256 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4501256 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50472/solr [junit4] 2> 4501257 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 4501257 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4501257 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4501258 INFO (zkCallback-3652-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6f36a934 name:ZooKeeperConnection Watcher:127.0.0.1:50472 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4501258 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4501259 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4501260 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4501261 INFO (zkCallback-3653-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ae2288 name:ZooKeeperConnection Watcher:127.0.0.1:50472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4501261 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4501262 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 4501263 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 4501266 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 4501268 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 4501269 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 4501271 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 4501273 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 4501274 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 4501275 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 4501277 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42176_ [junit4] 2> 4501277 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:42176_ [junit4] 2> 4501279 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 4501280 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 4501281 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4501281 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94623097389580292-127.0.0.1:42176_-n_0000000000 [junit4] 2> 4501282 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42176_ [junit4] 2> 4501282 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 4501283 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.Overseer Overseer (id=94623097389580292-127.0.0.1:42176_-n_0000000000) starting [junit4] 2> 4501284 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 4501289 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 4501290 INFO (OverseerCollectionConfigSetProcessor-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 4501290 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4501290 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 4501317 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 4501317 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 4501318 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores [junit4] 2> 4501319 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 4501319 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1/ [junit4] 2> 4501319 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 4501320 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 4501320 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:42176_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 4501320 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 4501320 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 4501322 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42176";, [junit4] 2> "node_name":"127.0.0.1:42176_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 4501322 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42176";, [junit4] 2> "node_name":"127.0.0.1:42176_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4501322 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 4501323 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 4501323 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 4501324 INFO (zkCallback-3653-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 4502323 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 4502323 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 4502324 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 4502324 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 4502324 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4502324 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1/' [junit4] 2> 4502338 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 4502340 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 4502343 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4502349 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 4502350 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4502354 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 4502408 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 4502409 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 4502409 WARN (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2) [junit4] 2> 4502409 WARN (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3) [junit4] 2> 4502411 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] 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=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 4502411 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4502411 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4502411 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 4502411 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 4502412 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1/data [junit4] 2> 4502412 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1/data/index/ [junit4] 2> 4502412 WARN (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 4502412 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1/data/index [junit4] 2> 4502416 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/index-MMapDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@752342e6),segFN=segments_1,generation=1} [junit4] 2> 4502416 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 4502416 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1/data/ [junit4] 2> 4502416 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 4502418 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4502420 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4502421 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4502421 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4502422 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4502422 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.PluginBag requestHandler : '/replication' created with startup=lazy [junit4] 2> 4502436 WARN (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 4502436 WARN (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 4502436 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin,/admin/ping,/update,/admin/file [junit4] 2> 4502436 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4502437 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4502437 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4502438 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 4502438 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 4502439 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/index-MMapDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@752342e6),segFN=segments_1,generation=1} [junit4] 2> 4502439 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 4502439 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7525dfff[collection1] main [junit4] 2> 4502439 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 4502440 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4502440 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4502440 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4502441 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 4502441 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4502441 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4502441 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4502441 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 4502450 WARN (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.a.AdminHandlers <requestHandler name="/admin/" [junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It is not required anymore [junit4] 2> 4502451 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets [junit4] 2> 4502451 INFO (searcherExecutor-8625-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7525dfff[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4502451 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 4502451 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index [junit4] 2> 4502451 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1513969560028446720 [junit4] 2> 4502453 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1513969560028446720 [junit4] 2> 4502453 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 4502454 INFO (coreLoadExecutor-8624-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 4502454 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:42176 collection:control_collection shard:shard1 [junit4] 2> 4502455 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 4502460 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/94623097389580292-core_node1-n_0000000000 [junit4] 2> 4502460 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 4502462 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 4502462 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 4502462 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:42176/collection1/ [junit4] 2> 4502462 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> 4502462 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 4502462 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:42176/collection1/ has no replicas [junit4] 2> 4502462 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:42176/collection1/ shard1 [junit4] 2> 4502463 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 4502465 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 4502467 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:42176";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 4502570 INFO (zkCallback-3653-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 4502618 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:42176/collection1/ and leader is http://127.0.0.1:42176/collection1/ [junit4] 2> 4502619 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:42176 [junit4] 2> 4502619 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 4502619 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 4502619 INFO (coreZkRegister-8618-thread-1-processing-n:127.0.0.1:42176_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:42176_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 4502621 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42176";, [junit4] 2> "node_name":"127.0.0.1:42176_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 4502621 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:42176";, [junit4] 2> "node_name":"127.0.0.1:42176_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4502723 INFO (zkCallback-3653-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 4502822 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4502823 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4502824 INFO (zkCallback-3655-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@419e9a33 name:ZooKeeperConnection Watcher:127.0.0.1:50472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4502824 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4502824 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4502824 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4502826 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4503167 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1 [junit4] 2> 4503168 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001 [junit4] 2> 4503169 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 4503171 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@79c22712{/,null,AVAILABLE} [junit4] 2> 4503171 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1238db8c{HTTP/1.1}{127.0.0.1:60472} [junit4] 2> 4503171 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.e.j.s.Server Started @4506675ms [junit4] 2> 4503171 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=60472, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores} [junit4] 2> 4503171 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 4503172 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/' [junit4] 2> 4503186 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4503187 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4503188 INFO (zkCallback-3656-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@35e610d5 name:ZooKeeperConnection Watcher:127.0.0.1:50472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4503188 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4503188 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4503189 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4503189 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/solr.xml [junit4] 2> 4503194 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores [junit4] 2> 4503194 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.CoreContainer New CoreContainer 88131643 [junit4] 2> 4503194 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/] [junit4] 2> 4503194 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/lib [junit4] 2> 4503194 WARN (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/lib). [junit4] 2> 4503201 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] 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> 4503202 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4503203 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4503203 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4503203 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50472/solr [junit4] 2> 4503203 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 4503203 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4503203 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4503204 INFO (zkCallback-3658-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2a96b2a6 name:ZooKeeperConnection Watcher:127.0.0.1:50472 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4503204 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4503205 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4503206 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4503207 INFO (zkCallback-3659-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@616c033 name:ZooKeeperConnection Watcher:127.0.0.1:50472/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4503207 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4503212 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4504217 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60472_ [junit4] 2> 4504217 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60472_ [junit4] 2> 4504218 INFO (zkCallback-3655-thread-1) [ ] 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> 4504218 INFO (zkCallback-3659-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ ] 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> 4504218 INFO (zkCallback-3653-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ ] 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> 4504220 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4504221 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94623097389580296-127.0.0.1:60472_-n_0000000001 [junit4] 2> 4504222 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94623097389580292-127.0.0.1:42176_-n_0000000000 to know if I could be the leader [junit4] 2> 4504241 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 4504241 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 4504242 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores [junit4] 2> 4504243 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 4504243 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1/ [junit4] 2> 4504243 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 4504244 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 4504244 INFO (TEST-TestSQLHandler.doTest-seed#[8DBBA9930FEE5899]) [n:127.0.0.1:60472_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 4504244 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 4504245 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 4504246 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60472";, [junit4] 2> "node_name":"127.0.0.1:60472_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 4504246 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 4504247 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60472";, [junit4] 2> "node_name":"127.0.0.1:60472_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4504247 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 4504247 INFO (OverseerStateUpdate-94623097389580292-127.0.0.1:42176_-n_0000000000) [n:127.0.0.1:42176_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 4504248 INFO (zkCallback-3655-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4504248 INFO (zkCallback-3653-thread-1-processing-n:127.0.0.1:42176_) [n:127.0.0.1:42176_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4504249 INFO (zkCallback-3659-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4505247 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 4505247 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 4505248 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 4505248 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 4505249 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4505249 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1/' [junit4] 2> 4505262 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 4505267 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 4505270 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4505277 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 4505278 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4505282 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 4505342 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 4505343 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 4505361 WARN (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2) [junit4] 2> 4505362 WARN (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3) [junit4] 2> 4505364 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] 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=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 4505364 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4505364 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4505364 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1/], dataDir=[null] [junit4] 2> 4505364 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 4505364 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1/data [junit4] 2> 4505365 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 4505365 WARN (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 4505365 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1/data/index [junit4] 2> 4505369 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/index-MMapDirectory-002 lockFactory=org.apache.lucene.store.NativeFSLockFactory@752342e6),segFN=segments_1,generation=1} [junit4] 2> 4505369 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 4505369 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1/data/ [junit4] 2> 4505369 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/shard-1-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 4505372 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4505375 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4505376 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4505377 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4505377 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4505378 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.PluginBag requestHandler : '/replication' created with startup=lazy [junit4] 2> 4505379 WARN (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 4505379 WARN (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [org.apache.solr.handler.admin.AdminHandlers]. Please consult documentation how to replace it accordingly. [junit4] 2> 4505379 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin,/admin/ping,/update,/admin/file [junit4] 2> 4505380 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4505380 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4505380 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4505381 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 4505381 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 4505383 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.handler.TestSQLHandler_8DBBA9930FEE5899-001/index-MMapDirectory-002 lockFactory=org.apache.lucene.store.NativeFSLockFactory@752342e6),segFN=segments_1,generation=1} [junit4] 2> 4505383 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 4505383 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@73e7e9ca[collection1] main [junit4] 2> 4505384 INFO (coreLoadExecutor-8634-thread-1-processing-n:127.0.0.1:60472_) [n:127.0.0.1:60472_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 4505385 INFO (coreLoadExecutor-8634-thread-1-processing-n [...truncated too long message...] .0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/commons-collections-3.2.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jcl-over-slf4j-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/presto-parser-0.108.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/build/analysis/icu/lucene-analyzers-icu-6.0.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/contrib/analysis-extras/lib/icu4j-54.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.8/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.1.17.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/temp/junit4-J1-20151002_194259_204.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/temp/junit4-J1-20151002_194259_204.suites -stdin [junit4] ERROR: JVM J1 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1505) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:133) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:963) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:960) [junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] at java.lang.Thread.run(Thread.java:745) BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. Total time: 198 minutes 18 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
