lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Uwe Schindler" <...@thetaphi.de>
Subject RE: [JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 3074 - Still Failing!
Date Tue, 09 Feb 2016 07:43:05 GMT
I fixed a few more in Streams/SQL:

 <http://git-wip-us.apache.org/repos/asf/lucene-solr/diff/8dddd88d> http://git-wip-us.apache.org/repos/asf/lucene-solr/diff/8dddd88d

 

The ones here were in fact exactly the ones mentioned in the examples of this blog and are the reason why forbiddenapis exists:

http://blog.thetaphi.de/2012/07/default-locales-default-charsets-and.html

 

Uwe

 

-----

Uwe Schindler

H.-H.-Meier-Allee 63, D-28213 Bremen

 <http://www.thetaphi.de/> http://www.thetaphi.de

eMail: uwe@thetaphi.de

 

From: Joel Bernstein [mailto:joelsolr@gmail.com] 
Sent: Tuesday, February 09, 2016 1:53 AM
To: lucene dev <dev@lucene.apache.org>
Subject: Re: [JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 3074 - Still Failing!

 

I didn't have a specific reason for using Locale.getDefault() it just seemed reasonable. I'll use Locale.ROOT in the future.




Joel Bernstein

 <http://joelsolr.blogspot.com/> http://joelsolr.blogspot.com/

 

On Mon, Feb 8, 2016 at 6:36 PM, Uwe Schindler < <mailto:uwe@thetaphi.de> uwe@thetaphi.de> wrote:

Hi,

 

Stupid question: Why did it use Locale.getDefault() at all – Locale.ROOT would have been fine (but equalsIgnoreCase is of course better)? We implemented forbidden-apis to prevent such bugs, but you used Locale.getDefault() explicitely – this confuses me. :)

Uwe

 

-----

Uwe Schindler

H.-H.-Meier-Allee 63, D-28213 Bremen

 <http://www.thetaphi.de/> http://www.thetaphi.de

eMail:  <mailto:uwe@thetaphi.de> uwe@thetaphi.de

 

From: Joel Bernstein [mailto: <mailto:joelsolr@gmail.com> joelsolr@gmail.com] 
Sent: Tuesday, February 09, 2016 12:16 AM
To: lucene dev < <mailto:dev@lucene.apache.org> dev@lucene.apache.org>
Subject: Re: [JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 3074 - Still Failing!

 

Committed a fix. Thanks Kevin!




Joel Bernstein

 <http://joelsolr.blogspot.com/> http://joelsolr.blogspot.com/

 

On Mon, Feb 8, 2016 at 6:06 PM, Joel Bernstein < <mailto:joelsolr@gmail.com> joelsolr@gmail.com> wrote:

Yep, Kevin your suggestion worked like a charm. I'll commit a fix shortly. 




Joel Bernstein

 <http://joelsolr.blogspot.com/> http://joelsolr.blogspot.com/

 

On Mon, Feb 8, 2016 at 6:04 PM, Joel Bernstein < <mailto:joelsolr@gmail.com> joelsolr@gmail.com> wrote:

You're right the limit check was risky! I'll see what I can do to fix this. Thanks!




Joel Bernstein

 <http://joelsolr.blogspot.com/> http://joelsolr.blogspot.com/

 

On Mon, Feb 8, 2016 at 6:00 PM, Kevin Risden < <mailto:risdenk@avalonconsult.com> risdenk@avalonconsult.com> wrote:

Looks like it is related to the Turkish locale and the containsLimit check in StatementImpl with toLowerCase. Might be able to replace the return with:

 

return "limit".equalsIgnoreCase(secondToLastToken);




Kevin Risden
Hadoop Tech Lead |  <http://www.avalonconsult.com/> Avalon Consulting, LLC

M:  <tel:732%20213%208417> 732 213 8417

 <http://www.linkedin.com/company/avalon-consulting-llc> LinkedIn |  <http://www.google.com/+AvalonConsultingLLC> Google+ |  <https://twitter.com/avalonconsult> Twitter

 

-------------------------------------------------------------------------------------------------------------

This message (including any attachments) contains confidential information 

intended for a specific individual and purpose, and is protected by law. If 

you are not the intended recipient, you should delete this message. Any 

disclosure, copying, or distribution of this message, or the taking of any 

action based on it, is strictly prohibited.

 

On Mon, Feb 8, 2016 at 4:56 PM, Joel Bernstein < <mailto:joelsolr@gmail.com> joelsolr@gmail.com> wrote:

This reproduces. I'll take a look.




Joel Bernstein

 <http://joelsolr.blogspot.com/> http://joelsolr.blogspot.com/

 

On Mon, Feb 8, 2016 at 5:37 PM, Policeman Jenkins Server < <mailto:jenkins@thetaphi.de> jenkins@thetaphi.de> wrote:

Build:  <http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/3074/> http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/3074/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC

3 tests failed.
FAILED:  org.apache.solr.client.solrj.io.sql.JdbcTest.doTest

Error Message:
java.sql.SQLException: Couldn't read first tuple

Stack Trace:
java.sql.SQLException: java.sql.SQLException: Couldn't read first tuple
        at __randomizedtesting.SeedInfo.seed([51B9C66A5C7CA003:F6FD7ECE31C7B3BA]:0)
        at org.apache.solr.client.solrj.io.sql.StatementImpl.executeQuery(StatementImpl.java:71)
        at org.apache.solr.client.solrj.io.sql.JdbcTest.doTest(JdbcTest.java:181)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
        at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:964)
        at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
        at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)
Caused by: java.sql.SQLException: Couldn't read first tuple
        at org.apache.solr.client.solrj.io.sql.ResultSetImpl.<init>(ResultSetImpl.java:81)
        at org.apache.solr.client.solrj.io.sql.StatementImpl.executeQuery(StatementImpl.java:68)
        ... 42 more
Caused by: java.io.IOException: -->  <http://127.0.0.1:58470/kwl/gt/collection1/:line> http://127.0.0.1:58470/kwl/gt/collection1/:line 1:75: mismatched input 'limit' expecting <EOF>
        at org.apache.solr.client.solrj.io.stream.SolrStream.read(SolrStream.java:177)
        at org.apache.solr.client.solrj.io.stream.PushBackStream.read(PushBackStream.java:82)
        at org.apache.solr.client.solrj.io.sql.ResultSetImpl.<init>(ResultSetImpl.java:71)
        ... 43 more


FAILED:  junit.framework.TestSuite.org.apache.solr.client.solrj.io.sql.JdbcTest

Error Message:
5 threads leaked from SUITE scope at org.apache.solr.client.solrj.io.sql.JdbcTest:     1) Thread[id=1175, name=zkCallback-439-thread-1, state=TIMED_WAITING, group=TGRP-JdbcTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)         at java.lang.Thread.run(Thread.java:745)    2) Thread[id=1173, name=TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]-SendThread( <http://127.0.0.1:58457> 127.0.0.1:58457), state=TIMED_WAITING, group=TGRP-JdbcTest]         at java.lang.Thread.sleep(Native Method)         at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)         at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)         at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)    3) Thread[id=1202, name=zkCallback-439-thread-3, state=TIMED_WAITING, group=TGRP-JdbcTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)         at java.lang.Thread.run(Thread.java:745)    4) Thread[id=1174, name=TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]-EventThread, state=WAITING, group=TGRP-JdbcTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)    5) Thread[id=1201, name=zkCallback-439-thread-2, state=TIMED_WAITING, group=TGRP-JdbcTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)         at java.lang.Thread.run(Thread.java:745)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 5 threads leaked from SUITE scope at org.apache.solr.client.solrj.io.sql.JdbcTest:
   1) Thread[id=1175, name=zkCallback-439-thread-1, state=TIMED_WAITING, group=TGRP-JdbcTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
        at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
        at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
        at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
   2) Thread[id=1173, name=TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]-SendThread( <http://127.0.0.1:58457> 127.0.0.1:58457), state=TIMED_WAITING, group=TGRP-JdbcTest]
        at java.lang.Thread.sleep(Native Method)
        at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
        at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
        at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
   3) Thread[id=1202, name=zkCallback-439-thread-3, state=TIMED_WAITING, group=TGRP-JdbcTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
        at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
        at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
        at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
   4) Thread[id=1174, name=TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]-EventThread, state=WAITING, group=TGRP-JdbcTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
        at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)
   5) Thread[id=1201, name=zkCallback-439-thread-2, state=TIMED_WAITING, group=TGRP-JdbcTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
        at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
        at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
        at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
        at __randomizedtesting.SeedInfo.seed([51B9C66A5C7CA003]:0)


FAILED:  junit.framework.TestSuite.org.apache.solr.client.solrj.io.sql.JdbcTest

Error Message:
There are still zombie threads that couldn't be terminated:    1) Thread[id=1175, name=zkCallback-439-thread-1, state=TIMED_WAITING, group=TGRP-JdbcTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)         at java.lang.Thread.run(Thread.java:745)    2) Thread[id=1173, name=TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]-SendThread( <http://127.0.0.1:58457> 127.0.0.1:58457), state=TIMED_WAITING, group=TGRP-JdbcTest]         at java.lang.Thread.sleep(Native Method)         at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)         at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)         at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)    3) Thread[id=1202, name=zkCallback-439-thread-3, state=TIMED_WAITING, group=TGRP-JdbcTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)         at java.lang.Thread.run(Thread.java:745)    4) Thread[id=1201, name=zkCallback-439-thread-2, state=TIMED_WAITING, group=TGRP-JdbcTest]         at sun.misc.Unsafe.park(Native Method)         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)         at java.lang.Thread.run(Thread.java:745)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
   1) Thread[id=1175, name=zkCallback-439-thread-1, state=TIMED_WAITING, group=TGRP-JdbcTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
        at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
        at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
        at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
   2) Thread[id=1173, name=TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]-SendThread( <http://127.0.0.1:58457> 127.0.0.1:58457), state=TIMED_WAITING, group=TGRP-JdbcTest]
        at java.lang.Thread.sleep(Native Method)
        at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
        at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
        at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
   3) Thread[id=1202, name=zkCallback-439-thread-3, state=TIMED_WAITING, group=TGRP-JdbcTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
        at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
        at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
        at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
   4) Thread[id=1201, name=zkCallback-439-thread-2, state=TIMED_WAITING, group=TGRP-JdbcTest]
        at sun.misc.Unsafe.park(Native Method)
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
        at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
        at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
        at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:745)
        at __randomizedtesting.SeedInfo.seed([51B9C66A5C7CA003]:0)




Build Log:
[...truncated 12678 lines...]
   [junit4] Suite: org.apache.solr.client.solrj.io.sql.JdbcTest
   [junit4]   2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/init-core-data-001
   [junit4]   2> 114671 INFO  (SUITE-JdbcTest-seed#[51B9C66A5C7CA003]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 114671 INFO  (SUITE-JdbcTest-seed#[51B9C66A5C7CA003]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /kwl/gt
   [junit4]   2> 114687 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 114687 INFO  (Thread-145) [    ] o.a.s.c.ZkTestServer client port: <http://0.0.0.0/0.0.0.0:0> 0.0.0.0/0.0.0.0:0
   [junit4]   2> 114687 INFO  (Thread-145) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 114689 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.ZkTestServer start zk server on port:58457
   [junit4]   2> 114690 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 114693 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 114701 INFO  (zkCallback-418-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher  <mailto:org.apache.solr.common.cloud.ConnectionManager@3336fde9> org.apache.solr.common.cloud.ConnectionManager@3336fde9 name:ZooKeeperConnection Watcher: <http://127.0.0.1:58457> 127.0.0.1:58457 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 114701 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 114701 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 114701 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 114707 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 114709 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 114710 INFO  (zkCallback-419-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher  <mailto:org.apache.solr.common.cloud.ConnectionManager@14847d5c> org.apache.solr.common.cloud.ConnectionManager@14847d5c name:ZooKeeperConnection Watcher: <http://127.0.0.1:58457/solr> 127.0.0.1:58457/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 114710 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 114710 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 114710 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 114713 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 114718 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 114725 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 114728 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig-sql.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 114728 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 114734 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractZkTestCase put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/schema-sql.xml to /configs/conf1/schema.xml
   [junit4]   2> 114734 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 114738 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractZkTestCase skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml because it doesn't exist
   [junit4]   2> 114738 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractZkTestCase skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/stopwords.txt because it doesn't exist
   [junit4]   2> 114738 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractZkTestCase skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/protwords.txt because it doesn't exist
   [junit4]   2> 114738 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractZkTestCase skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/currency.xml because it doesn't exist
   [junit4]   2> 114739 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractZkTestCase skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/enumsConfig.xml because it doesn't exist
   [junit4]   2> 114739 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractZkTestCase skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/open-exchange-rates.json because it doesn't exist
   [junit4]   2> 114739 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractZkTestCase skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/mapping-ISOLatin1Accent.txt because it doesn't exist
   [junit4]   2> 114740 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractZkTestCase skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/old_synonyms.txt because it doesn't exist
   [junit4]   2> 114740 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractZkTestCase skipping /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/solrj/src/test-files/solrj/solr/collection1/conf/synonyms.txt because it doesn't exist
   [junit4]   2> 114809 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores/collection1
   [junit4]   2> 114816 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 114819 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.e.j.s.h.ContextHandler Started  <mailto:o.e.j.s.ServletContextHandler@197a69c3%7b/kwl/gt,null,AVAILABLE%7d> o.e.j.s.ServletContextHandler@197a69c3{/kwl/gt,null,AVAILABLE}
   [junit4]   2> 114840 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.e.j.s.ServerConnector Started ServerConnector@2ddbb1ff{HTTP/1.1}{ <http://127.0.0.1:58460> 127.0.0.1:58460}
   [junit4]   2> 114841 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.e.j.s.Server Started @120270ms
   [junit4]   2> 114841 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/tempDir-001/control/data, hostContext=/kwl/gt, hostPort=58460, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores}
   [junit4]   2> 114841 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():  <mailto:sun.misc.Launcher$AppClassLoader@1540e19d> sun.misc.Launcher$AppClassLoader@1540e19d
   [junit4]   2> 114841 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001'
   [junit4]   2> 114842 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 114842 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 114842 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 114844 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 114846 INFO  (zkCallback-420-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher  <mailto:org.apache.solr.common.cloud.ConnectionManager@6bc09f00> org.apache.solr.common.cloud.ConnectionManager@6bc09f00 name:ZooKeeperConnection Watcher: <http://127.0.0.1:58457/solr> 127.0.0.1:58457/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 114846 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 114846 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 114850 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 114850 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/solr.xml
   [junit4]   2> 114860 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores
   [junit4]   2> 114860 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.CoreContainer New CoreContainer 1444482479
   [junit4]   2> 114860 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001]
   [junit4]   2> 114860 WARN  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/lib
   [junit4]   2> 114861 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] 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> 114863 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 114863 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 114863 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 114863 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.ZkContainer Zookeeper client= <http://127.0.0.1:58457/solr> 127.0.0.1:58457/solr
   [junit4]   2> 114863 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 114863 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 114865 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 114867 INFO  (zkCallback-423-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher  <mailto:org.apache.solr.common.cloud.ConnectionManager@6b75addb> org.apache.solr.common.cloud.ConnectionManager@6b75addb name:ZooKeeperConnection Watcher: <http://127.0.0.1:58457> 127.0.0.1:58457 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 114868 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 114868 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 114870 WARN  (NIOServerCxn.Factory: <http://0.0.0.0/0.0.0.0:0> 0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from client sessionid 0x152c305fb4a0003, likely client has closed socket
   [junit4]   2>        at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
   [junit4]   2>        at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 114872 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 114874 INFO  (zkCallback-424-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.ConnectionManager Watcher  <mailto:org.apache.solr.common.cloud.ConnectionManager@5332e96f> org.apache.solr.common.cloud.ConnectionManager@5332e96f name:ZooKeeperConnection Watcher: <http://127.0.0.1:58457/solr> 127.0.0.1:58457/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 114875 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 114877 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 114882 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 114893 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 114905 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 114910 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 114914 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 114922 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 114929 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 114931 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 114933 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58460_kwl%2Fgt
   [junit4]   2> 114935 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:58460_kwl%2Fgt
   [junit4]   2> 114938 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 114941 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 114944 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 114945 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000
   [junit4]   2> 114945 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58460_kwl%2Fgt
   [junit4]   2> 114945 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 114948 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.Overseer Overseer (id=95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) starting
   [junit4]   2> 114952 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 114966 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 114967 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 114968 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
   [junit4]   2> 114968 INFO  (OverseerCollectionConfigSetProcessor-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
   [junit4]   2> 114980 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
   [junit4]   2> 115005 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 115005 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 115007 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores
   [junit4]   2> 115008 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 115008 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores/collection1
   [junit4]   2> 115008 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 115013 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
   [junit4]   2> 115013 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 115013 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 115013 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 115017 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":" <http://127.0.0.1:58460/kwl/gt> http://127.0.0.1:58460/kwl/gt",
   [junit4]   2>   "node_name":"127.0.0.1:58460_kwl%2Fgt",
   [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> 115017 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 115017 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":" <http://127.0.0.1:58460/kwl/gt> http://127.0.0.1:58460/kwl/gt",
   [junit4]   2>   "node_name":"127.0.0.1:58460_kwl%2Fgt",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 115018 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 115019 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 115023 INFO  (zkCallback-424-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt    ] 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> 115024 INFO  (zkCallback-424-thread-2-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt    ] 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> 116018 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 116019 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 116020 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 116020 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection]
   [junit4]   2> 116021 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper
   [junit4]   2> 116021 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores/collection1'
   [junit4]   2> 116021 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 116022 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 116024 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
   [junit4]   2> 116033 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 116036 WARN  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <maxMergeDocs> is deprecated, configure it on the relevant <mergePolicyFactory> instead.
   [junit4]   2> 116036 WARN  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergeFactor> is deprecated, configure it on the relevant <mergePolicyFactory> instead.
   [junit4]   2> 116037 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 116070 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 116084 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 116176 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 116177 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 116178 WARN  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
   [junit4]   2> 116178 WARN  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
   [junit4]   2> 116187 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
   [junit4]   2> 116187 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 116187 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 116187 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores/collection1/data/]
   [junit4]   2> 116187 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
   [junit4]   2> 116188 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores/collection1/data
   [junit4]   2> 116189 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores/collection1/data/index/
   [junit4]   2> 116189 WARN  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 116189 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores/collection1/data/index
   [junit4]   2> 116194 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@62fb5261  <mailto:lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5ffd269b),segFN=segments_1,generation=1> lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5ffd269b),segFN=segments_1,generation=1}
   [junit4]   2> 116194 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 116194 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58460_kwl%2Fgt 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 /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores/collection1/data/
   [junit4]   2> 116195 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 116197 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116216 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 116218 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 116219 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 116220 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 116220 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.PluginBag requestHandler : '/replication' created with startup=lazy
   [junit4]   2> 116260 WARN  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116260 WARN  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116261 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116261 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116263 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116263 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116267 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 116267 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 116267 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@62fb5261  <mailto:lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5ffd269b),segFN=segments_1,generation=1> lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5ffd269b),segFN=segments_1,generation=1}
   [junit4]   2> 116267 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 116267 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@18466b18[collection1] main]
   [junit4]   2> 116268 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116269 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116269 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n: <http://127.0.0.1:5> 127.0.0.1:58460_kwl%2Fgt 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> 116272 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 116272 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 116272 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116273 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116273 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116273 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 116287 WARN  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116287 INFO  (searcherExecutor-167-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@18466b18[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 116287 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116287 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116287 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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> 116287 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt 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 1525647563056742400
   [junit4]   2> 116291 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 3.0ms to seed version buckets with highest version 1525647563056742400
   [junit4]   2> 116291 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 116292 INFO  (coreLoadExecutor-166-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 116293 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address: <http://127.0.0.1:58460/kwl/gt> http://127.0.0.1:58460/kwl/gt collection:control_collection shard:shard1
   [junit4]   2> 116295 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt 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> 116303 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt 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/95352972586385412-core_node1-n_0000000000
   [junit4]   2> 116303 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=0
   [junit4]   2> 116306 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 116306 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt 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> 116306 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to  <http://127.0.0.1:58460/kwl/gt/collection1/> http://127.0.0.1:58460/kwl/gt/collection1/
   [junit4]   2> 116306 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 116307 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy  <http://127.0.0.1:58460/kwl/gt/collection1/> http://127.0.0.1:58460/kwl/gt/collection1/ has no replicas
   [junit4]   2> 116308 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] 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> 116308 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 116314 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
   [junit4]   2> 116317 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader:  <http://127.0.0.1:58460/kwl/gt/collection1/> http://127.0.0.1:58460/kwl/gt/collection1/ shard1
   [junit4]   2> 116318 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "base_url":" <http://127.0.0.1:58460/kwl/gt> http://127.0.0.1:58460/kwl/gt",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 116425 INFO  (zkCallback-424-thread-2-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt    ] 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> 116425 INFO  (zkCallback-424-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt    ] 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> 116472 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are  <http://127.0.0.1:58460/kwl/gt/collection1/> http://127.0.0.1:58460/kwl/gt/collection1/ and leader is  <http://127.0.0.1:58460/kwl/gt/collection1/> http://127.0.0.1:58460/kwl/gt/collection1/
   [junit4]   2> 116472 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL= <http://127.0.0.1:58460/kwl/gt> http://127.0.0.1:58460/kwl/gt
   [junit4]   2> 116472 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 116472 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 116472 INFO  (coreZkRegister-160-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58460_kwl%2Fgt 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> 116477 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":" <http://127.0.0.1:58460/kwl/gt> http://127.0.0.1:58460/kwl/gt",
   [junit4]   2>   "node_name":"127.0.0.1:58460_kwl%2Fgt",
   [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> 116478 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":" <http://127.0.0.1:58460/kwl/gt> http://127.0.0.1:58460/kwl/gt",
   [junit4]   2>   "node_name":"127.0.0.1:58460_kwl%2Fgt",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 116518 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 116519 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 116521 INFO  (zkCallback-426-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher  <mailto:org.apache.solr.common.cloud.ConnectionManager@cfd51e6> org.apache.solr.common.cloud.ConnectionManager@cfd51e6 name:ZooKeeperConnection Watcher: <http://127.0.0.1:58457/solr> 127.0.0.1:58457/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 116522 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 116522 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 116522 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
   [junit4]   2> 116528 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 116528 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 116528 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 116529 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 116532 INFO  (zkCallback-427-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher  <mailto:org.apache.solr.common.cloud.ConnectionManager@3807e95d> org.apache.solr.common.cloud.ConnectionManager@3807e95d name:ZooKeeperConnection Watcher: <http://127.0.0.1:58457/solr> 127.0.0.1:58457/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 116532 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 116533 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 116540 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] 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: 2
   [junit4]   2> 116540 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 116541 INFO  (zkCallback-424-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt    ] 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> 116542 INFO  (zkCallback-424-thread-2-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt    ] 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> 116544 INFO  (zkCallback-426-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [1])
   [junit4]   2> 116587 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/shard-1-001/cores/collection1
   [junit4]   2> 116589 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/shard-1-001
   [junit4]   2> 116590 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.e.j.s.Server jetty-9.2.13.v20150730
   [junit4]   2> 116593 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.e.j.s.h.ContextHandler Started  <mailto:o.e.j.s.ServletContextHandler@7b1b4225%7b/kwl/gt,null,AVAILABLE%7d> o.e.j.s.ServletContextHandler@7b1b4225{/kwl/gt,null,AVAILABLE}
   [junit4]   2> 116593 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.e.j.s.ServerConnector Started ServerConnector@7c82644f{HTTP/1.1}{ <http://127.0.0.1:58466> 127.0.0.1:58466}
   [junit4]   2> 116593 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.e.j.s.Server Started @122022ms
   [junit4]   2> 116593 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/kwl/gt, hostPort=58466, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/shard-1-001/cores}
   [junit4]   2> 116593 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():  <mailto:sun.misc.Launcher$AppClassLoader@1540e19d> sun.misc.Launcher$AppClassLoader@1540e19d
   [junit4]   2> 116593 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/shard-1-001'
   [junit4]   2> 116594 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 116594 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
   [junit4]   2> 116594 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 116594 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 116597 INFO  (zkCallback-428-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher  <mailto:org.apache.solr.common.cloud.ConnectionManager@70128b30> org.apache.solr.common.cloud.ConnectionManager@70128b30 name:ZooKeeperConnection Watcher: <http://127.0.0.1:58457/solr> 127.0.0.1:58457/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 116597 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 116597 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 116599 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
   [junit4]   2> 116599 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/shard-1-001/solr.xml
   [junit4]   2> 116613 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/shard-1-001/cores
   [junit4]   2> 116613 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.CoreContainer New CoreContainer 1466778331
   [junit4]   2> 116613 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/shard-1-001]
   [junit4]   2> 116613 WARN  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.CoreContainer Couldn't add files from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/shard-1-001/lib to classpath: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/shard-1-001/lib
   [junit4]   2> 116613 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] 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> 116615 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 116616 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 116616 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 116616 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.ZkContainer Zookeeper client= <http://127.0.0.1:58457/solr> 127.0.0.1:58457/solr
   [junit4]   2> 116616 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 116616 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 116617 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 116619 INFO  (zkCallback-431-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher  <mailto:org.apache.solr.common.cloud.ConnectionManager@3876c034> org.apache.solr.common.cloud.ConnectionManager@3876c034 name:ZooKeeperConnection Watcher: <http://127.0.0.1:58457> 127.0.0.1:58457 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 116620 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 116621 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 116624 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 116627 INFO  (zkCallback-432-thread-1-processing-n:127.0.0.1:58466_kwl%2Fgt) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.c.ConnectionManager Watcher  <mailto:org.apache.solr.common.cloud.ConnectionManager@7d351fb3> org.apache.solr.common.cloud.ConnectionManager@7d351fb3 name:ZooKeeperConnection Watcher: <http://127.0.0.1:58457/solr> 127.0.0.1:58457/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 116627 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 116644 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
   [junit4]   2> 116658 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 117657 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58466_kwl%2Fgt
   [junit4]   2> 117658 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:58466_kwl%2Fgt
   [junit4]   2> 117660 INFO  (zkCallback-432-thread-1-processing-n:127.0.0.1:58466_kwl%2Fgt) [n:127.0.0.1:58466_kwl%2Fgt    ] 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> 117660 INFO  (zkCallback-424-thread-2-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt    ] 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> 117660 INFO  (zkCallback-426-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> 117661 INFO  (zkCallback-424-thread-1-processing-n:127.0.0.1:58460_kwl%2Fgt) [n:127.0.0.1:58460_kwl%2Fgt    ] 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> 117664 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 117665 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95352972586385417-127.0.0.1:58466_kwl%2Fgt-n_0000000001
   [junit4]   2> 117666 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000 to know if I could be the leader
   [junit4]   2> 117678 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 117678 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 117680 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/shard-1-001/cores
   [junit4]   2> 117681 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 117681 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.CorePropertiesLocator Found core collection1 in /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.sql.JdbcTest_51B9C66A5C7CA003-001/shard-1-001/cores/collection1
   [junit4]   2> 117682 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
   [junit4]   2> 117688 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.s.SolrDispatchFilter user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-solrj/test/J0
   [junit4]   2> 117688 INFO  (TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]) [n:127.0.0.1:58466_kwl%2Fgt    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 117701 INFO  (coreLoadExecutor-176-thread-1-processing-n:127.0.0.1:58466_kwl%2Fgt) [n:127.0.0.1:58466_kwl%2Fgt c:collection1   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 117701 INFO  (coreLoadExecutor-176-thread-1-processing-n:127.0.0.1:58466_kwl%2Fgt) [n:127.0.0.1:58466_kwl%2Fgt c:collection1   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 117705 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":" <http://127.0.0.1:58466/kwl/gt> http://127.0.0.1:58466/kwl/gt",
   [junit4]   2>   "node_name":"127.0.0.1:58466_kwl%2Fgt",
   [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> 117706 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":" <http://127.0.0.1:58466/kwl/gt> http://127.0.0.1:58466/kwl/gt",
   [junit4]   2>   "node_name":"127.0.0.1:58466_kwl%2Fgt",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"collection1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 117706 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2
   [junit4]   2> 117706 INFO  (OverseerStateUpdate-95352972586385412-127.0.0.1:58460_kwl%2Fgt-n_0000000000) [n:127.0.0.1:58460_kwl%2Fgt    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
   [junit4]   2> 117709 INFO  (coreLoadExecutor-176-thread-1-processing-n:127.0.0.1:58466_kwl%2Fgt) [n:127.0.0.1:58466_kwl%2Fgt c:collection1   x:collection1] o.a.s.c.ZkController Registering watch for collection collection1
   [junit4]   2> 117709 INFO  (coreLoadExecutor-176-thread-1-processing-n:127.0.0.1:58466_kwl%2Fgt) [n:127.0.0.1:58466_kwl%2Fgt c:collection1   x:collection1] o.a.s.c.c.ZkStateReader addZ

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

4/Oracle Corporation 1.8.0_72 (64-bit)/cpus=3,threads=5,free=87054624,total=320864256
   [junit4]   2> NOTE: All tests run in this JVM: [TestCloudSolrClientConnections, StreamExpressionTest, TestZkConfigManager, TestSolrProperties, TestHash, JdbcTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=JdbcTest -Dtests.seed=51B9C66A5C7CA003 -Dtests.slow=true -Dtests.locale=tr -Dtests.timezone=Asia/Ulan_Bator -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J0 | JdbcTest (suite) <<<
   [junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 5 threads leaked from SUITE scope at org.apache.solr.client.solrj.io.sql.JdbcTest:
   [junit4]    >    1) Thread[id=1175, name=zkCallback-439-thread-1, state=TIMED_WAITING, group=TGRP-JdbcTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
   [junit4]    >         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    2) Thread[id=1173, name=TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]-SendThread( <http://127.0.0.1:58457> 127.0.0.1:58457), state=TIMED_WAITING, group=TGRP-JdbcTest]
   [junit4]    >         at java.lang.Thread.sleep(Native Method)
   [junit4]    >         at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
   [junit4]    >         at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
   [junit4]    >         at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
   [junit4]    >    3) Thread[id=1202, name=zkCallback-439-thread-3, state=TIMED_WAITING, group=TGRP-JdbcTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
   [junit4]    >         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    4) Thread[id=1174, name=TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]-EventThread, state=WAITING, group=TGRP-JdbcTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
   [junit4]    >         at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
   [junit4]    >         at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
   [junit4]    >         at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:494)
   [junit4]    >    5) Thread[id=1201, name=zkCallback-439-thread-2, state=TIMED_WAITING, group=TGRP-JdbcTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
   [junit4]    >         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >        at __randomizedtesting.SeedInfo.seed([51B9C66A5C7CA003]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=1175, name=zkCallback-439-thread-1, state=TIMED_WAITING, group=TGRP-JdbcTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
   [junit4]    >         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    2) Thread[id=1173, name=TEST-JdbcTest.doTest-seed#[51B9C66A5C7CA003]-SendThread( <http://127.0.0.1:58457> 127.0.0.1:58457), state=TIMED_WAITING, group=TGRP-JdbcTest]
   [junit4]    >         at java.lang.Thread.sleep(Native Method)
   [junit4]    >         at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:101)
   [junit4]    >         at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:940)
   [junit4]    >         at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1003)
   [junit4]    >    3) Thread[id=1202, name=zkCallback-439-thread-3, state=TIMED_WAITING, group=TGRP-JdbcTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
   [junit4]    >         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >    4) Thread[id=1201, name=zkCallback-439-thread-2, state=TIMED_WAITING, group=TGRP-JdbcTest]
   [junit4]    >         at sun.misc.Unsafe.park(Native Method)
   [junit4]    >         at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460)
   [junit4]    >         at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362)
   [junit4]    >         at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1066)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
   [junit4]    >         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    >        at __randomizedtesting.SeedInfo.seed([51B9C66A5C7CA003]:0)
   [junit4] Completed [49/77 (1!)] on J0 in 35.78s, 1 test, 3 errors <<< FAILURES!

[...truncated 95 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:740: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:684: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:59: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:246: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:524: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1457: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1014: There were test failures: 77 suites, 523 tests, 2 suite-level errors, 1 error, 187 ignored [seed: 51B9C66A5C7CA003]

Total time: 77 minutes 39 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:  <mailto:dev-unsubscribe@lucene.apache.org> dev-unsubscribe@lucene.apache.org
For additional commands, e-mail:  <mailto:dev-help@lucene.apache.org> dev-help@lucene.apache.org

 

 

 

 

 

 


Mime
View raw message