lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Joel Bernstein <joels...@gmail.com>
Subject Re: [JENKINS] Lucene-Solr-trunk-Linux (32bit/jdk1.8.0_60) - Build # 14382 - Failure!
Date Fri, 02 Oct 2015 19:45:15 GMT
Looks like an issue with the request context which is breaking the
SortingResponseWriter. I've seen errors with the request context before but
they've never been reproducible like this.

Joel Bernstein
http://joelsolr.blogspot.com/

On Fri, Oct 2, 2015 at 3:34 PM, Joel Bernstein <joelsolr@gmail.com> wrote:

> This is a new error, which is reproducing for me locally. There hasn't
> been any changes to this code for weeks though. I'll see what the issue is.
>
> Joel Bernstein
> http://joelsolr.blogspot.com/
>
> On Fri, Oct 2, 2015 at 1:19 PM, Policeman Jenkins Server <
> jenkins@thetaphi.de> wrote:
>
>> Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/14382/
>> Java: 32bit/jdk1.8.0_60 -server -XX:+UseSerialGC
>>
>> 1 tests failed.
>> FAILED:  org.apache.solr.handler.TestSQLHandler.doTest
>>
>> Error Message:
>> -->
>> https://127.0.0.1:44818/puwf/collection1:java.util.concurrent.ExecutionException:
>> java.io.IOException: --> https://127.0.0.1:58743/puwf/collection1/: An
>> exception has occurred on the server, refer to server log for details.
>>
>> Stack Trace:
>> java.io.IOException: -->
>> https://127.0.0.1:44818/puwf/collection1:java.util.concurrent.ExecutionException:
>> java.io.IOException: --> https://127.0.0.1:58743/puwf/collection1/: An
>> exception has occurred on the server, refer to server log for details.
>>         at
>> __randomizedtesting.SeedInfo.seed([1770F0E01B63BAD4:B034484476D8A96D]: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 9524 lines...]
>>    [junit4] Suite: org.apache.solr.handler.TestSQLHandler
>>    [junit4]   2> Creating dataDir:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/init-core-data-001
>>    [junit4]   2> 184495 INFO
>> (SUITE-TestSQLHandler-seed#[1770F0E01B63BAD4]-worker) [    ]
>> o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true)
>>    [junit4]   2> 184495 INFO
>> (SUITE-TestSQLHandler-seed#[1770F0E01B63BAD4]-worker) [    ]
>> o.a.s.BaseDistributedSearchTestCase Setting hostContext system property:
>> /puwf/
>>    [junit4]   2> 184496 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
>>    [junit4]   2> 184496 INFO  (Thread-514) [    ] o.a.s.c.ZkTestServer
>> client port:0.0.0.0/0.0.0.0:0
>>    [junit4]   2> 184496 INFO  (Thread-514) [    ] o.a.s.c.ZkTestServer
>> Starting server
>>    [junit4]   2> 184596 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.ZkTestServer start zk server on port:38645
>>    [junit4]   2> 184596 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
>>    [junit4]   2> 184596 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
>>    [junit4]   2> 184598 INFO  (zkCallback-294-thread-1) [    ]
>> o.a.s.c.c.ConnectionManager Watcher
>> org.apache.solr.common.cloud.ConnectionManager@11462c5
>> name:ZooKeeperConnection Watcher:127.0.0.1:38645 got event WatchedEvent
>> state:SyncConnected type:None path:null path:null type:None
>>    [junit4]   2> 184598 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
>>    [junit4]   2> 184598 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkACLProvider
>>    [junit4]   2> 184598 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /solr
>>    [junit4]   2> 184599 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
>>    [junit4]   2> 184600 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
>>    [junit4]   2> 184600 INFO  (zkCallback-295-thread-1) [    ]
>> o.a.s.c.c.ConnectionManager Watcher
>> org.apache.solr.common.cloud.ConnectionManager@114d6d3
>> name:ZooKeeperConnection Watcher:127.0.0.1:38645/solr got event
>> WatchedEvent state:SyncConnected type:None path:null path:null type:None
>>    [junit4]   2> 184600 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
>>    [junit4]   2> 184600 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkACLProvider
>>    [junit4]   2> 184600 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /collections/collection1
>>    [junit4]   2> 184601 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
>>    [junit4]   2> 184602 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
>>    [junit4]   2> 184602 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
>>    [junit4]   2> 184603 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractZkTestCase put
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-sql.xml
>> to /configs/conf1/solrconfig.xml
>>    [junit4]   2> 184603 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
>>    [junit4]   2> 184604 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractZkTestCase put
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema-sql.xml
>> to /configs/conf1/schema.xml
>>    [junit4]   2> 184604 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
>>    [junit4]   2> 184605 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractZkTestCase put
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
>> to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
>>    [junit4]   2> 184605 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath:
>> /configs/conf1/solrconfig.snippet.randomindexconfig.xml
>>    [junit4]   2> 184606 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractZkTestCase put
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
>> to /configs/conf1/stopwords.txt
>>    [junit4]   2> 184606 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
>>    [junit4]   2> 184606 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractZkTestCase put
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt
>> to /configs/conf1/protwords.txt
>>    [junit4]   2> 184606 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
>>    [junit4]   2> 184607 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractZkTestCase put
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml
>> to /configs/conf1/currency.xml
>>    [junit4]   2> 184607 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
>>    [junit4]   2> 184607 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractZkTestCase put
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
>> to /configs/conf1/enumsConfig.xml
>>    [junit4]   2> 184608 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
>>    [junit4]   2> 184608 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractZkTestCase put
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
>> to /configs/conf1/open-exchange-rates.json
>>    [junit4]   2> 184608 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
>>    [junit4]   2> 184609 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractZkTestCase put
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
>> to /configs/conf1/mapping-ISOLatin1Accent.txt
>>    [junit4]   2> 184609 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
>>    [junit4]   2> 184609 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractZkTestCase put
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
>> to /configs/conf1/old_synonyms.txt
>>    [junit4]   2> 184610 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
>>    [junit4]   2> 184610 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractZkTestCase put
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
>> to /configs/conf1/synonyms.txt
>>    [junit4]   2> 184610 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
>>    [junit4]   2> 184611 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
>>    [junit4]   2> 184611 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
>>    [junit4]   2> 184612 INFO  (zkCallback-296-thread-1) [    ]
>> o.a.s.c.c.ConnectionManager Watcher
>> org.apache.solr.common.cloud.ConnectionManager@1c7ebc9
>> name:ZooKeeperConnection Watcher:127.0.0.1:38645/solr got event
>> WatchedEvent state:SyncConnected type:None path:null path:null type:None
>>    [junit4]   2> 184612 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
>>    [junit4]   2> 184612 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkACLProvider
>>    [junit4]   2> 184668 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.SolrTestCaseJ4 Writing core.properties file to
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1
>>    [junit4]   2> 184682 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ] o.e.j.s.Server
>> jetty-9.2.13.v20150730
>>    [junit4]   2> 184690 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4315a8
>> {/puwf,null,AVAILABLE}
>>    [junit4]   2> 184808 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.e.j.s.ServerConnector Started ServerConnector@d8f53e{SSL-http/1.1}{
>> 127.0.0.1:55566}
>>    [junit4]   2> 184808 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ] o.e.j.s.Server
>> Started @186332ms
>>    [junit4]   2> 184808 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.s.e.JettySolrRunner Jetty properties:
>> {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/tempDir-001/control/data,
>> hostContext=/puwf, hostPort=55566,
>> coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores}
>>    [junit4]   2> 184808 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
>> sun.misc.Launcher$AppClassLoader@e2f2a
>>    [junit4]   2> 184808 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
>> '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/'
>>    [junit4]   2> 184819 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
>>    [junit4]   2> 184819 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
>>    [junit4]   2> 184821 INFO  (zkCallback-297-thread-1) [    ]
>> o.a.s.c.c.ConnectionManager Watcher
>> org.apache.solr.common.cloud.ConnectionManager@1a8b110
>> name:ZooKeeperConnection Watcher:127.0.0.1:38645/solr got event
>> WatchedEvent state:SyncConnected type:None path:null path:null type:None
>>    [junit4]   2> 184821 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
>>    [junit4]   2> 184821 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkACLProvider
>>    [junit4]   2> 184822 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
>> ZooKeeper)
>>    [junit4]   2> 184822 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.SolrXmlConfig Loading container configuration from
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/solr.xml
>>    [junit4]   2> 184825 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.CoresLocator Config-defined core root directory:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores
>>    [junit4]   2> 184825 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.CoreContainer New CoreContainer 32540868
>>    [junit4]   2> 184825 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.CoreContainer Loading cores into CoreContainer
>> [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/]
>>    [junit4]   2> 184825 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.CoreContainer loading shared library:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/lib
>>    [junit4]   2> 184825 WARN
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to
>> classloader: lib (resolved as:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/lib).
>>    [junit4]   2> 184831 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> 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> 184833 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with
>> params: socketTimeout=340000&connTimeout=45000&retry=true
>>    [junit4]   2> 184834 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
>>    [junit4]   2> 184834 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.l.LogWatcher Registering Log Listener [Log4j
>> (org.slf4j.impl.Log4jLoggerFactory)]
>>    [junit4]   2> 184834 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38645/solr
>>    [junit4]   2> 184834 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.ZkController zkHost includes chroot
>>    [junit4]   2> 184834 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
>>    [junit4]   2> 184834 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
>>    [junit4]   2> 184835 INFO  (zkCallback-299-thread-1) [    ]
>> o.a.s.c.c.ConnectionManager Watcher
>> org.apache.solr.common.cloud.ConnectionManager@1d5c369
>> name:ZooKeeperConnection Watcher:127.0.0.1:38645 got event WatchedEvent
>> state:SyncConnected type:None path:null path:null type:None
>>    [junit4]   2> 184836 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
>>    [junit4]   2> 184836 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkACLProvider
>>    [junit4]   2> 184837 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
>>    [junit4]   2> 184837 INFO
>> (zkCallback-300-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.c.ConnectionManager Watcher
>> org.apache.solr.common.cloud.ConnectionManager@1258d2f
>> name:ZooKeeperConnection Watcher:127.0.0.1:38645/solr got event
>> WatchedEvent state:SyncConnected type:None path:null path:null type:None
>>    [junit4]   2> 184838 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
>>    [junit4]   2> 184838 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
>>    [junit4]   2> 184840 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
>>    [junit4]   2> 184841 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
>>    [junit4]   2> 184843 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
>>    [junit4]   2> 184844 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
>>    [junit4]   2> 184845 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
>>    [junit4]   2> 184846 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
>>    [junit4]   2> 184846 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
>>    [junit4]   2> 184847 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /security.json
>>    [junit4]   2> 184847 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.ZkController Register node as live in
>> ZooKeeper:/live_nodes/127.0.0.1:55566_puwf
>>    [junit4]   2> 184848 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:55566_puwf
>>    [junit4]   2> 184848 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
>>    [junit4]   2> 184849 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
>>    [junit4]   2> 184849 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.Overseer Overseer (id=null) closing
>>    [junit4]   2> 184850 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.LeaderElector Joined leadership election with path:
>> /overseer_elect/election/94621192339718149-127.0.0.1:55566_puwf-n_0000000000
>>    [junit4]   2> 184850 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.OverseerElectionContext I am going to be the leader
>> 127.0.0.1:55566_puwf
>>    [junit4]   2> 184850 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
>>    [junit4]   2> 184851 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.Overseer Overseer
>> (id=94621192339718149-127.0.0.1:55566_puwf-n_0000000000) starting
>>    [junit4]   2> 184851 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
>>    [junit4]   2> 184855 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting
>> OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
>> autoReplicaFailoverWaitAfterExpiration=30000
>> autoReplicaFailoverBadNodeExpiration=60000
>>    [junit4]   2> 184858 INFO
>> (OverseerCollectionConfigSetProcessor-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.OverseerTaskProcessor Process
>> current queue of overseer operations
>>    [junit4]   2> 184858 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
>>    [junit4]   2> 184858 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.Overseer Starting to work on the
>> main queue
>>    [junit4]   2> 184886 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for
>> authorization module.
>>    [junit4]   2> 184886 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.CoreContainer No authentication plugin used.
>>    [junit4]   2> 184887 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.CoresLocator Looking for core definitions underneath
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores
>>    [junit4]   2> 184887 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1,
>> config=solrconfig.xml, transient=false, schema=schema.xml,
>> loadOnStartup=true, configSetProperties=configsetprops.json,
>> instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1,
>> collection=control_collection,
>> absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1/,
>> coreNodeName=, dataDir=data/, shard=}
>>    [junit4]   2> 184887 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.CoresLocator Found core collection1 in
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1/
>>    [junit4]   2> 184887 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.c.CoresLocator Found 1 core definitions
>>    [junit4]   2> 184888 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.s.SolrDispatchFilter
>> user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
>>    [junit4]   2> 184888 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:55566_puwf
>>   ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
>>    [junit4]   2> 184888 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.ZkController publishing state=down
>>    [junit4]   2> 184888 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.ZkController numShards not found on descriptor - reading it from
>> system property
>>    [junit4]   2> 184890 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.Overseer processMessage: queueSize:
>> 1, message = {
>>    [junit4]   2>   "core":"collection1",
>>    [junit4]   2>   "roles":null,
>>    [junit4]   2>   "base_url":"https://127.0.0.1:55566/puwf",
>>    [junit4]   2>   "node_name":"127.0.0.1:55566_puwf",
>>    [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> 184890 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.ZkController look for our core node name
>>    [junit4]   2> 184890 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.o.ReplicaMutator Update state
>> numShards=1 message={
>>    [junit4]   2>   "core":"collection1",
>>    [junit4]   2>   "roles":null,
>>    [junit4]   2>   "base_url":"https://127.0.0.1:55566/puwf",
>>    [junit4]   2>   "node_name":"127.0.0.1:55566_puwf",
>>    [junit4]   2>   "numShards":"1",
>>    [junit4]   2>   "state":"down",
>>    [junit4]   2>   "shard":null,
>>    [junit4]   2>   "collection":"control_collection",
>>    [junit4]   2>   "operation":"state"}
>>    [junit4]   2> 184890 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.o.ClusterStateMutator building a
>> new cName: control_collection
>>    [junit4]   2> 184891 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.o.ReplicaMutator Assigning new node
>> to shard shard=shard1
>>    [junit4]   2> 184892 INFO
>> (zkCallback-300-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf    ] 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> 185890 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.ZkController waiting to find shard id in clusterstate for
>> collection1
>>    [junit4]   2> 185890 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.ZkController Check for collection zkNode:control_collection
>>    [junit4]   2> 185891 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.ZkController Collection zkNode exists
>>    [junit4]   2> 185891 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.c.ZkStateReader Load collection config
>> from:/collections/control_collection
>>    [junit4]   2> 185891 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.c.ZkStateReader path=/collections/control_collection
>> configName=conf1 specified config exists in ZooKeeper
>>    [junit4]   2> 185891 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
>> '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1/'
>>    [junit4]   2> 185898 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.Config loaded config solrconfig.xml with version 0
>>    [junit4]   2> 185900 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.SolrConfig current version of requestparams : -1
>>    [junit4]   2> 185903 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
>>    [junit4]   2> 185917 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
>>    [junit4]   2> 185917 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
>>    [junit4]   2> 185921 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.s.IndexSchema [collection1] Schema name=test
>>    [junit4]   2> 185975 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.s.IndexSchema default search field in schema is text
>>    [junit4]   2> 185976 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.s.IndexSchema unique key field: id
>>    [junit4]   2> 185976 WARN
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination
>> for multiple copyFields (2)
>>    [junit4]   2> 185976 WARN
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.s.IndexSchema Field text is not multivalued and destination for
>> multiple copyFields (3)
>>    [junit4]   2> 185977 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
>>    [junit4]   2> 185977 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection   x:collection1]
>> o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration
>> from collection control_collection
>>    [junit4]   2> 185978 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
>>    [junit4]   2> 185978 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
>> [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1/],
>> dataDir=[null]
>>    [junit4]   2> 185978 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core:
>> collection1
>>    [junit4]   2> 185978 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1/data
>>    [junit4]   2> 185978 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.SolrCore New index directory detected: old=null
>> new=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1/data/index/
>>    [junit4]   2> 185978 WARN
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory
>> '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1/data/index'
>> doesn't exist. Creating new index...
>>    [junit4]   2> 185978 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1/data/index
>>    [junit4]   2> 185979 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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(RAMDirectory@97a9e
>> lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11047a6
>> ),segFN=segments_1,generation=1}
>>    [junit4]   2> 185979 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.SolrCore newest commit generation = 1
>>    [junit4]   2> 185979 INFO
>> (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55566_puwf
>> 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
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1/data/
>>    [junit4]   2> 185979 WARN
>> (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.DirectoryFactory
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/control-001/cores/collection1/data/
>> does not point to a valid data directory; skipping clean-up of old index
>> directories.
>>    [junit4]   2> 185980 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 185984 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
>>    [junit4]   2> 185984 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
>>    [junit4]   2> 185984 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
>>    [junit4]   2> 185985 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
>>    [junit4]   2> 185985 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.PluginBag requestHandler : '/replication' created
>> with startup=lazy
>>    [junit4]   2> 186005 WARN
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186005 WARN
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186006 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186006 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186006 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186006 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186007 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
>>    [junit4]   2> 186007 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
>>    [junit4]   2> 186007 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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(RAMDirectory@97a9e
>> lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11047a6
>> ),segFN=segments_1,generation=1}
>>    [junit4]   2> 186007 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.SolrCore newest commit generation = 1
>>    [junit4]   2> 186007 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2d2ed9[collection1]
>> main
>>    [junit4]   2> 186007 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186008 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186008 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186008 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO
>> with znodeBase: /configs/conf1
>>    [junit4]   2> 186008 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs:
>> {}
>>    [junit4]   2> 186008 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186008 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186009 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186009 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.r.RestManager Initializing 0 registered
>> ManagedResources
>>    [junit4]   2> 186015 WARN
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186015 INFO
>> (searcherExecutor-645-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
>> [collection1] Registered new searcher Searcher@2d2ed9[collection1]
>> main{ExitableDirectoryReader(UninvertingDirectoryReader())}
>>    [junit4]   2> 186015 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186015 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186015 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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> 186015 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf 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 1513939079027228672
>>    [junit4]   2> 186017 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with
>> highest version 1513939079027228672
>>    [junit4]   2> 186017 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
>>    [junit4]   2> 186017 INFO
>> (coreLoadExecutor-644-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf c:control_collection s:shard1 r:core_node1
>> x:collection1] o.a.s.c.CoreContainer registering core: collection1
>>    [junit4]   2> 186018 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.ZkController Register replica - core:collection1 address:
>> https://127.0.0.1:55566/puwf collection:control_collection shard:shard1
>>    [junit4]   2> 186018 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> 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> 186019 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> 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/94621192339718149-core_node1-n_0000000000
>>    [junit4]   2> 186019 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.ShardLeaderElectionContext Running the leader process for shard
>> shard1
>>    [junit4]   2> 186020 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
>>    [junit4]   2> 186020 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> 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> 186020 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.SyncStrategy Sync replicas to
>> https://127.0.0.1:55566/puwf/collection1/
>>    [junit4]   2> 186020 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
>>    [junit4]   2> 186020 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.SyncStrategy https://127.0.0.1:55566/puwf/collection1/ has no
>> replicas
>>    [junit4]   2> 186020 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.ShardLeaderElectionContext I am the new leader:
>> https://127.0.0.1:55566/puwf/collection1/ shard1
>>    [junit4]   2> 186020 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] 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> 186030 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.c.SolrZkClient makePath:
>> /collections/control_collection/leaders/shard1
>>    [junit4]   2> 186031 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
>>    [junit4]   2> 186033 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] 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":"https://127.0.0.1:55566/puwf",
>>    [junit4]   2>   "core":"collection1",
>>    [junit4]   2>   "state":"active"} current state version: 1
>>    [junit4]   2> 186134 INFO
>> (zkCallback-300-thread-1-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf    ] 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> 186183 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.ZkController We are https://127.0.0.1:55566/puwf/collection1/
>> and leader is https://127.0.0.1:55566/puwf/collection1/
>>    [junit4]   2> 186183 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=
>> https://127.0.0.1:55566/puwf
>>    [junit4]   2> 186183 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.ZkController I am the leader, no recovery necessary
>>    [junit4]   2> 186183 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> c:control_collection s:shard1 r:core_node1 x:collection1]
>> o.a.s.c.ZkController publishing state=active
>>    [junit4]   2> 186183 INFO
>> (coreZkRegister-638-thread-1-processing-n:127.0.0.1:55566_puwf
>> x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55566_puwf
>> 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> 186184 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] 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":"https://127.0.0.1:55566/puwf",
>>    [junit4]   2>   "node_name":"127.0.0.1:55566_puwf",
>>    [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> 186184 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] 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":"https://127.0.0.1:55566/puwf",
>>    [junit4]   2>   "node_name":"127.0.0.1:55566_puwf",
>>    [junit4]   2>   "numShards":"1",
>>    [junit4]   2>   "state":"active",
>>    [junit4]   2>   "shard":"shard1",
>>    [junit4]   2>   "collection":"control_collection",
>>    [junit4]   2>   "operation":"state"}
>>    [junit4]   2> 186286 INFO
>> (zkCallback-300-thread-2-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf    ] 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> 186392 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
>>    [junit4]   2> 186392 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
>>    [junit4]   2> 186393 INFO  (zkCallback-302-thread-1) [    ]
>> o.a.s.c.c.ConnectionManager Watcher
>> org.apache.solr.common.cloud.ConnectionManager@1c66b8c
>> name:ZooKeeperConnection Watcher:127.0.0.1:38645/solr got event
>> WatchedEvent state:SyncConnected type:None path:null path:null type:None
>>    [junit4]   2> 186393 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
>>    [junit4]   2> 186393 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkACLProvider
>>    [junit4]   2> 186393 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
>>    [junit4]   2> 186394 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
>> loss:false
>>    [junit4]   2> 186394 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with
>> stateFormat=2
>>    [junit4]   2> 186394 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
>>    [junit4]   2> 186395 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
>>    [junit4]   2> 186395 INFO  (zkCallback-303-thread-1) [    ]
>> o.a.s.c.c.ConnectionManager Watcher
>> org.apache.solr.common.cloud.ConnectionManager@16896be
>> name:ZooKeeperConnection Watcher:127.0.0.1:38645/solr got event
>> WatchedEvent state:SyncConnected type:None path:null path:null type:None
>>    [junit4]   2> 186395 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
>>    [junit4]   2> 186395 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkACLProvider
>>    [junit4]   2> 186396 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.Overseer processMessage: queueSize:
>> 1, message = {
>>    [junit4]   2>   "operation":"create",
>>    [junit4]   2>   "name":"collection1",
>>    [junit4]   2>   "numShards":"2",
>>    [junit4]   2>   "stateFormat":"2"} current state version: 3
>>    [junit4]   2> 186396 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.o.ClusterStateMutator building a
>> new cName: collection1
>>    [junit4]   2> 186447 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.SolrTestCaseJ4 Writing core.properties file to
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1
>>    [junit4]   2> 186448 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001
>>    [junit4]   2> 186448 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ] o.e.j.s.Server
>> jetty-9.2.13.v20150730
>>    [junit4]   2> 186449 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a672e4
>> {/puwf,null,AVAILABLE}
>>    [junit4]   2> 186454 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.e.j.s.ServerConnector Started ServerConnector@b94d3d{SSL-http/1.1}{
>> 127.0.0.1:44818}
>>    [junit4]   2> 186454 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ] o.e.j.s.Server
>> Started @187978ms
>>    [junit4]   2> 186454 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.s.e.JettySolrRunner Jetty properties:
>> {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/tempDir-001/jetty1,
>> solrconfig=solrconfig.xml, hostContext=/puwf, hostPort=44818,
>> coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores}
>>    [junit4]   2> 186454 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
>> sun.misc.Launcher$AppClassLoader@e2f2a
>>    [junit4]   2> 186455 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
>> '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/'
>>    [junit4]   2> 186464 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
>>    [junit4]   2> 186464 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
>>    [junit4]   2> 186465 INFO  (zkCallback-304-thread-1) [    ]
>> o.a.s.c.c.ConnectionManager Watcher
>> org.apache.solr.common.cloud.ConnectionManager@2a696b
>> name:ZooKeeperConnection Watcher:127.0.0.1:38645/solr got event
>> WatchedEvent state:SyncConnected type:None path:null path:null type:None
>>    [junit4]   2> 186465 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
>>    [junit4]   2> 186465 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkACLProvider
>>    [junit4]   2> 186466 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
>> ZooKeeper)
>>    [junit4]   2> 186466 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.SolrXmlConfig Loading container configuration from
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/solr.xml
>>    [junit4]   2> 186469 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.CoresLocator Config-defined core root directory:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores
>>    [junit4]   2> 186469 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.CoreContainer New CoreContainer 19617008
>>    [junit4]   2> 186469 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.CoreContainer Loading cores into CoreContainer
>> [instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/]
>>    [junit4]   2> 186469 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.CoreContainer loading shared library:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/lib
>>    [junit4]   2> 186469 WARN
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to
>> classloader: lib (resolved as:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/lib).
>>    [junit4]   2> 186474 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> 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> 186475 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with
>> params: socketTimeout=340000&connTimeout=45000&retry=true
>>    [junit4]   2> 186476 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
>>    [junit4]   2> 186476 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.l.LogWatcher Registering Log Listener [Log4j
>> (org.slf4j.impl.Log4jLoggerFactory)]
>>    [junit4]   2> 186476 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38645/solr
>>    [junit4]   2> 186476 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.ZkController zkHost includes chroot
>>    [junit4]   2> 186476 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
>>    [junit4]   2> 186477 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
>>    [junit4]   2> 186478 INFO  (zkCallback-306-thread-1) [    ]
>> o.a.s.c.c.ConnectionManager Watcher
>> org.apache.solr.common.cloud.ConnectionManager@1dc8aaf
>> name:ZooKeeperConnection Watcher:127.0.0.1:38645 got event WatchedEvent
>> state:SyncConnected type:None path:null path:null type:None
>>    [junit4]   2> 186478 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
>>    [junit4]   2> 186478 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.a.s.c.c.SolrZkClient Using default ZkACLProvider
>>    [junit4]   2> 186479 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
>>    [junit4]   2> 186480 INFO
>> (zkCallback-307-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf    ] o.a.s.c.c.ConnectionManager Watcher
>> org.apache.solr.common.cloud.ConnectionManager@1db12cb
>> name:ZooKeeperConnection Watcher:127.0.0.1:38645/solr got event
>> WatchedEvent state:SyncConnected type:None path:null path:null type:None
>>    [junit4]   2> 186480 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
>>    [junit4]   2> 186482 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
>>    [junit4]   2> 186498 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.o.ZkStateWriter going to
>> create_collection /collections/collection1/state.json
>>    [junit4]   2> 187485 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.ZkController Register node as live in
>> ZooKeeper:/live_nodes/127.0.0.1:44818_puwf
>>    [junit4]   2> 187485 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:44818_puwf
>>    [junit4]   2> 187486 INFO  (zkCallback-302-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> 187486 INFO
>> (zkCallback-307-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf    ] 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> 187486 INFO
>> (zkCallback-300-thread-2-processing-n:127.0.0.1:55566_puwf)
>> [n:127.0.0.1:55566_puwf    ] 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> 187486 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.Overseer Overseer (id=null) closing
>>    [junit4]   2> 187487 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.LeaderElector Joined leadership election with path:
>> /overseer_elect/election/94621192339718154-127.0.0.1:44818_puwf-n_0000000001
>>    [junit4]   2> 187487 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.LeaderElector Watching path
>> /overseer_elect/election/94621192339718149-127.0.0.1:55566_puwf-n_0000000000
>> to know if I could be the leader
>>    [junit4]   2> 187507 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for
>> authorization module.
>>    [junit4]   2> 187507 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.CoreContainer No authentication plugin used.
>>    [junit4]   2> 187507 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.CoresLocator Looking for core definitions underneath
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores
>>    [junit4]   2> 187508 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1,
>> config=solrconfig.xml, transient=false, schema=schema.xml,
>> loadOnStartup=true, configSetProperties=configsetprops.json,
>> instanceDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1,
>> collection=collection1,
>> absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1/,
>> coreNodeName=, dataDir=data/, shard=}
>>    [junit4]   2> 187508 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.CoresLocator Found core collection1 in
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1/
>>    [junit4]   2> 187508 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.c.CoresLocator Found 1 core definitions
>>    [junit4]   2> 187509 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.ZkController publishing state=down
>>    [junit4]   2> 187509 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.ZkController numShards not found on descriptor - reading it from
>> system property
>>    [junit4]   2> 187509 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.s.SolrDispatchFilter
>> user.dir=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
>>    [junit4]   2> 187509 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:44818_puwf
>>   ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
>>    [junit4]   2> 187510 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.ZkController Registering watch for collection collection1
>>    [junit4]   2> 187510 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.c.ZkStateReader addZkWatch collection1
>>    [junit4]   2> 187510 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.c.ZkStateReader Add data for collection1 ver 0
>>    [junit4]   2> 187510 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.Overseer processMessage: queueSize:
>> 1, message = {
>>    [junit4]   2>   "core":"collection1",
>>    [junit4]   2>   "roles":null,
>>    [junit4]   2>   "base_url":"https://127.0.0.1:44818/puwf",
>>    [junit4]   2>   "node_name":"127.0.0.1:44818_puwf",
>>    [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> 187510 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.ZkController look for our core node name
>>    [junit4]   2> 187510 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.o.ReplicaMutator Update state
>> numShards=2 message={
>>    [junit4]   2>   "core":"collection1",
>>    [junit4]   2>   "roles":null,
>>    [junit4]   2>   "base_url":"https://127.0.0.1:44818/puwf",
>>    [junit4]   2>   "node_name":"127.0.0.1:44818_puwf",
>>    [junit4]   2>   "numShards":"2",
>>    [junit4]   2>   "state":"down",
>>    [junit4]   2>   "shard":null,
>>    [junit4]   2>   "collection":"collection1",
>>    [junit4]   2>   "operation":"state"}
>>    [junit4]   2> 187510 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.o.ReplicaMutator Collection already
>> exists with numShards=2
>>    [junit4]   2> 187510 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.o.ReplicaMutator Assigning new node
>> to shard shard=shard2
>>    [junit4]   2> 187612 INFO
>> (OverseerStateUpdate-94621192339718149-127.0.0.1:55566_puwf-n_0000000000)
>> [n:127.0.0.1:55566_puwf    ] o.a.s.c.o.ZkStateWriter going to
>> update_collection /collections/collection1/state.json version: 0
>>    [junit4]   2> 187612 INFO
>> (zkCallback-307-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf    ] o.a.s.c.c.ZkStateReader A cluster state
>> change: WatchedEvent state:SyncConnected type:NodeDataChanged
>> path:/collections/collection1/state.json for collection collection1 has
>> occurred - updating... (live nodes size: 2)
>>    [junit4]   2> 187612 INFO
>> (zkCallback-307-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf    ] o.a.s.c.c.ZkStateReader Updating data for
>> collection1 from 0 to 1
>>    [junit4]   2> 188510 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.ZkController waiting to find shard id in clusterstate for
>> collection1
>>    [junit4]   2> 188510 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.ZkController Check for collection zkNode:collection1
>>    [junit4]   2> 188511 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.ZkController Collection zkNode exists
>>    [junit4]   2> 188511 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1
>>    [junit4]   2> 188511 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1
>> specified config exists in ZooKeeper
>>    [junit4]   2> 188511 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
>> '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1/'
>>    [junit4]   2> 188519 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1] o.a.s.c.Config
>> loaded config solrconfig.xml with version 0
>>    [junit4]   2> 188521 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.SolrConfig current version of requestparams : -1
>>    [junit4]   2> 188523 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
>>    [junit4]   2> 188539 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1] o.a.s.c.Config
>> Loaded SolrConfig: solrconfig.xml
>>    [junit4]   2> 188539 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
>>    [junit4]   2> 188542 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.s.IndexSchema [collection1] Schema name=test
>>    [junit4]   2> 188596 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.s.IndexSchema default search field in schema is text
>>    [junit4]   2> 188596 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.s.IndexSchema unique key field: id
>>    [junit4]   2> 188597 WARN
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination
>> for multiple copyFields (2)
>>    [junit4]   2> 188597 WARN
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.s.IndexSchema Field text is not multivalued and destination for
>> multiple copyFields (3)
>>    [junit4]   2> 188598 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf 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=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
>>    [junit4]   2> 188598 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1   x:collection1]
>> o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration
>> from collection collection1
>>    [junit4]   2> 188598 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
>>    [junit4]   2> 188598 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
>> [/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1/],
>> dataDir=[null]
>>    [junit4]   2> 188598 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core:
>> collection1
>>    [junit4]   2> 188599 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1/data
>>    [junit4]   2> 188599 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.SolrCore New index directory detected: old=null
>> new=/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1/data/index/
>>    [junit4]   2> 188599 WARN
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory
>> '/home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1/data/index'
>> doesn't exist. Creating new index...
>>    [junit4]   2> 188599 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1/data/index
>>    [junit4]   2> 188599 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
>>    [junit4]   2>
>> commit{dir=MockDirectoryWrapper(RAMDirectory@129806c
>> lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cf5318
>> ),segFN=segments_1,generation=1}
>>    [junit4]   2> 188599 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.SolrCore newest commit generation = 1
>>    [junit4]   2> 188601 INFO
>> (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44818_puwf
>> 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
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1/data/
>>    [junit4]   2> 188601 WARN
>> (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44818_puwf
>> c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-1-001/cores/collection1/data/
>> does not point to a valid data directory; skipping clean-up of old index
>> directories.
>>    [junit4]   2> 188602 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as
>> default, creating implicit default
>>    [junit4]   2> 188604 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
>>    [junit4]   2> 188604 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
>>    [junit4]   2> 188604 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
>>    [junit4]   2> 188605 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
>>    [junit4]   2> 188605 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.PluginBag requestHandler : '/replication' created
>> with startup=lazy
>>    [junit4]   2> 188606 WARN
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf 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> 188606 WARN
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf 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> 188606 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf 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> 188606 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf 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> 188607 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
>> org.apache.solr.update.UpdateLog
>>    [junit4]   2> 188607 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf 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> 188607 INFO
>> (coreLoadExecutor-654-thread-1-processing-n:127.0.0.1:44818_puwf)
>> [n:127.0.0.1:44818_puwf c:collection1 s:shard2 r:core_node1
>> x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
>>    [junit4]   2> 188607 INFO  (coreLoadExecutor-654-t
>>
>> [...truncated too long message...]
>>
>>
>> r.TestSQLHandler_1770F0E01B63BAD4-001/shard-4-001/cores/collection1/data/index;done=false>>]
>>    [junit4]   2> 207989 INFO
>> (zkCallback-320-thread-2-processing-n:127.0.0.1:53872_puwf)
>> [n:127.0.0.1:53872_puwf c:collection1 s:shard1 r:core_node4
>> x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001/shard-4-001/cores/collection1/data/index
>>    [junit4]   2> 207990 WARN
>> (zkCallback-320-thread-2-processing-n:127.0.0.1:53872_puwf)
>> [n:127.0.0.1:53872_puwf c:collection1 s:shard1 r:core_node4
>> x:collection1] o.a.s.c.LeaderElector
>>    [junit4]   2> org.apache.solr.common.SolrException:
>> org.apache.zookeeper.KeeperException$SessionExpiredException:
>> KeeperErrorCode = Session expired for /overseer/queue
>>    [junit4]   2>        at
>> org.apache.solr.cloud.DistributedQueue.<init>(DistributedQueue.java:110)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.Overseer.getInQueue(Overseer.java:897)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.Overseer.getInQueue(Overseer.java:892)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:287)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:197)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:157)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:346)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:255)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.ShardLeaderElectionContext.rejoinLeaderElection(ElectionContext.java:547)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:311)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:197)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:157)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:58)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:388)
>>    [junit4]   2>        at
>> org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:269)
>>    [junit4]   2>        at
>> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
>>    [junit4]   2>        at
>> java.util.concurrent.FutureTask.run(FutureTask.java:266)
>>    [junit4]   2>        at
>> org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231)
>>    [junit4]   2>        at
>> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
>>    [junit4]   2>        at
>> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
>>    [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
>>    [junit4]   2> Caused by:
>> org.apache.zookeeper.KeeperException$SessionExpiredException:
>> KeeperErrorCode = Session expired for /overseer/queue
>>    [junit4]   2>        at
>> org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
>>    [junit4]   2>        at
>> org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
>>    [junit4]   2>        at
>> org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1045)
>>    [junit4]   2>        at
>> org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:319)
>>    [junit4]   2>        at
>> org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:316)
>>    [junit4]   2>        at
>> org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
>>    [junit4]   2>        at
>> org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:316)
>>    [junit4]   2>        at
>> org.apache.solr.common.cloud.ZkCmdExecutor.ensureExists(ZkCmdExecutor.java:90)
>>    [junit4]   2>        at
>> org.apache.solr.common.cloud.ZkCmdExecutor.ensureExists(ZkCmdExecutor.java:84)
>>    [junit4]   2>        at
>> org.apache.solr.cloud.DistributedQueue.<init>(DistributedQueue.java:108)
>>    [junit4]   2>        ... 20 more
>>    [junit4]   2> 207991 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.e.j.s.ServerConnector Stopped ServerConnector@789dd3{SSL-http/1.1}{
>> 127.0.0.1:0}
>>    [junit4]   2> 207991 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [    ]
>> o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@aa54aa
>> {/puwf,null,UNAVAILABLE}
>>    [junit4]   2> 207991 INFO
>> (TEST-TestSQLHandler.doTest-seed#[1770F0E01B63BAD4]) [n:127.0.0.1:53872_puwf
>> c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.ZkTestServer
>> connecting to 127.0.0.1:38645 38645
>>    [junit4]   2> 208097 INFO  (Thread-514) [    ] o.a.s.c.ZkTestServer
>> connecting to 127.0.0.1:38645 38645
>>    [junit4]   2> 208098 WARN  (Thread-514) [    ] o.a.s.c.ZkTestServer
>> Watch limit violations:
>>    [junit4]   2> Maximum concurrent create/delete watches above limit:
>>    [junit4]   2>
>>    [junit4]   2>        7       /solr/aliases.json
>>    [junit4]   2>        5       /solr/security.json
>>    [junit4]   2>        5       /solr/configs/conf1
>>    [junit4]   2>
>>    [junit4]   2> Maximum concurrent data watches above limit:
>>    [junit4]   2>
>>    [junit4]   2>        7       /solr/clusterstate.json
>>    [junit4]   2>        4       /solr/collections/collection1/state.json
>>    [junit4]   2>
>>    [junit4]   2> Maximum concurrent children watches above limit:
>>    [junit4]   2>
>>    [junit4]   2>        7       /solr/live_nodes
>>    [junit4]   2>        7       /solr/collections
>>    [junit4]   2>        5       /solr/overseer/queue
>>    [junit4]   2>        5       /solr/overseer/collection-queue-work
>>    [junit4]   2>        2       /solr/overseer/queue-work
>>    [junit4]   2>
>>    [junit4]   2> NOTE: reproduce with: ant test
>> -Dtestcase=TestSQLHandler -Dtests.method=doTest
>> -Dtests.seed=1770F0E01B63BAD4 -Dtests.multiplier=3 -Dtests.slow=true
>> -Dtests.locale=de_DE -Dtests.timezone=Asia/Muscat -Dtests.asserts=true
>> -Dtests.file.encoding=UTF-8
>>    [junit4] ERROR   23.6s J1 | TestSQLHandler.doTest <<<
>>    [junit4]    > Throwable #1: java.io.IOException: -->
>> https://127.0.0.1:44818/puwf/collection1:java.util.concurrent.ExecutionException:
>> java.io.IOException: --> https://127.0.0.1:58743/puwf/collection1/: An
>> exception has occurred on the server, refer to server log for details.
>>    [junit4]    >        at
>> __randomizedtesting.SeedInfo.seed([1770F0E01B63BAD4:B034484476D8A96D]:0)
>>    [junit4]    >        at
>> org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:178)
>>    [junit4]    >        at
>> org.apache.solr.handler.TestSQLHandler.getTuples(TestSQLHandler.java:1317)
>>    [junit4]    >        at
>> org.apache.solr.handler.TestSQLHandler.testBasicSelect(TestSQLHandler.java:232)
>>    [junit4]    >        at
>> org.apache.solr.handler.TestSQLHandler.doTest(TestSQLHandler.java:96)
>>    [junit4]    >        at
>> org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
>>    [junit4]    >        at
>> org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
>>    [junit4]    >        at java.lang.Thread.run(Thread.java:745)
>>    [junit4]   2> 208103 INFO
>> (SUITE-TestSQLHandler-seed#[1770F0E01B63BAD4]-worker) [n:127.0.0.1:53872_puwf
>> c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.SolrTestCaseJ4
>> ###deleteCore
>>    [junit4]   2> NOTE: leaving temporary files on disk at:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandler_1770F0E01B63BAD4-001
>>    [junit4]   2> Oct 02, 2015 4:53:58 PM
>> com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
>>    [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked
>> thread(s).
>>    [junit4]   2> NOTE: test params are:
>> codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST,
>> chunkSize=10, maxDocsPerChunk=1000, blockSize=9),
>> termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST,
>> chunkSize=10, blockSize=9)), sim=ClassicSimilarity, locale=de_DE,
>> timezone=Asia/Muscat
>>    [junit4]   2> NOTE: Linux 3.19.0-28-generic i386/Oracle Corporation
>> 1.8.0_60 (32-bit)/cpus=12,threads=1,free=84511848,total=155021312
>>    [junit4]   2> NOTE: All tests run in this JVM: [SortByFunctionTest,
>> TestScoreJoinQPScore, TestDistribDocBasedVersion, TestCryptoKeys,
>> TestMacros, UpdateRequestProcessorFactoryTest, TestAnalyzeInfixSuggestions,
>> MultiTermTest, TestRebalanceLeaders, HdfsRecoverLeaseTest, HighlighterTest,
>> ChaosMonkeyNothingIsSafeTest, TestBlendedInfixSuggestions, BlockCacheTest,
>> HdfsLockFactoryTest, DateRangeFieldTest, TestRTGBase, PreAnalyzedFieldTest,
>> ZkSolrClientTest, WordBreakSolrSpellCheckerTest,
>> VMParamsZkACLAndCredentialsProvidersTest, TestConfigOverlay,
>> TestPartialUpdateDeduplication, TestJsonFacets,
>> TestWordDelimiterFilterFactory, TestSQLHandler]
>>    [junit4] Completed [75/546] on J1 in 24.37s, 1 test, 1 error <<<
>> FAILURES!
>>
>> [...truncated 1494 lines...]
>> BUILD FAILED
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:775: The
>> following error occurred while executing this line:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:719: The
>> following error occurred while executing this line:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:59: The
>> following error occurred while executing this line:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:233: The
>> following error occurred while executing this line:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:516:
>> The following error occurred while executing this line:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1432:
>> The following error occurred while executing this line:
>> /home/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:987:
>> There were test failures: 546 suites (8 ignored), 2145 tests, 1 error, 71
>> ignored (35 assumptions) [seed: 1770F0E01B63BAD4]
>>
>> Total time: 48 minutes 18 seconds
>> Build step 'Invoke Ant' marked build as failure
>> Archiving artifacts
>> [WARNINGS] Skipping publisher since build result is FAILURE
>> Recording test results
>> Email was triggered for: Failure - Any
>> Sending email for trigger: Failure - Any
>>
>>
>>
>>
>> ---------------------------------------------------------------------
>> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
>> For additional commands, e-mail: dev-help@lucene.apache.org
>>
>
>

Mime
View raw message