You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/11/29 11:51:43 UTC

[JENKINS] Lucene-Solr-master-Windows (32bit/jdk1.8.0_102) - Build # 6255 - Still Unstable!

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6255/
Java: 32bit/jdk1.8.0_102 -server -XX:+UseG1GC

2 tests failed.
FAILED:  org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail

Error Message:
expected:<200> but was:<404>

Stack Trace:
java.lang.AssertionError: expected:<200> but was:<404>
	at __randomizedtesting.SeedInfo.seed([C423C2337603F98D:AC9CF719A699EB61]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.cancelDelegationToken(TestSolrCloudWithDelegationTokens.java:140)
	at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:294)
	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:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	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:811)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.util.TestSolrCLIRunExample.testInteractiveSolrCloudExample

Error Message:
Expected 10 to be found in the testCloudExamplePrompt collection but only found 3

Stack Trace:
java.lang.AssertionError: Expected 10 to be found in the testCloudExamplePrompt collection but only found 3
	at __randomizedtesting.SeedInfo.seed([C423C2337603F98D:1F5222F941763CEB]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.util.TestSolrCLIRunExample.testInteractiveSolrCloudExample(TestSolrCLIRunExample.java:457)
	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:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	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:811)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	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)




Build Log:
[...truncated 12246 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWithDelegationTokens_C423C2337603F98D-001\init-core-data-001
   [junit4]   2> 2412492 WARN  (SUITE-TestSolrCloudWithDelegationTokens-seed#[C423C2337603F98D]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=80 numCloses=80
   [junit4]   2> 2412500 INFO  (SUITE-TestSolrCloudWithDelegationTokens-seed#[C423C2337603F98D]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 2412509 INFO  (SUITE-TestSolrCloudWithDelegationTokens-seed#[C423C2337603F98D]-worker) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWithDelegationTokens_C423C2337603F98D-001\tempDir-001
   [junit4]   2> 2412509 INFO  (SUITE-TestSolrCloudWithDelegationTokens-seed#[C423C2337603F98D]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2412510 INFO  (Thread-4911) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2412510 INFO  (Thread-4911) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2412609 INFO  (SUITE-TestSolrCloudWithDelegationTokens-seed#[C423C2337603F98D]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:59667
   [junit4]   2> 2412618 WARN  (NIOServerCxn.Factory: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 0x158afcabde60000, 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> 2412620 INFO  (jetty-launcher-3812-thread-2) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2412620 INFO  (jetty-launcher-3812-thread-1) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2412623 INFO  (jetty-launcher-3812-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f27a72{/solr,null,AVAILABLE}
   [junit4]   2> 2412623 INFO  (jetty-launcher-3812-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@bd2018{/solr,null,AVAILABLE}
   [junit4]   2> 2412623 INFO  (jetty-launcher-3812-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@41ad5{HTTP/1.1,[http/1.1]}{127.0.0.1:59672}
   [junit4]   2> 2412627 INFO  (jetty-launcher-3812-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@16e9e54{HTTP/1.1,[http/1.1]}{127.0.0.1:59671}
   [junit4]   2> 2412630 INFO  (jetty-launcher-3812-thread-2) [    ] o.e.j.s.Server Started @2417282ms
   [junit4]   2> 2412630 INFO  (jetty-launcher-3812-thread-1) [    ] o.e.j.s.Server Started @2417282ms
   [junit4]   2> 2412630 INFO  (jetty-launcher-3812-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59672}
   [junit4]   2> 2412630 INFO  (jetty-launcher-3812-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59671}
   [junit4]   2> 2412630 ERROR (jetty-launcher-3812-thread-1) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2412630 ERROR (jetty-launcher-3812-thread-2) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2412631 INFO  (jetty-launcher-3812-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
   [junit4]   2> 2412631 INFO  (jetty-launcher-3812-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
   [junit4]   2> 2412631 INFO  (jetty-launcher-3812-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2412631 INFO  (jetty-launcher-3812-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2412631 INFO  (jetty-launcher-3812-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2412631 INFO  (jetty-launcher-3812-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2412631 INFO  (jetty-launcher-3812-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-11-29T11:14:09.627Z
   [junit4]   2> 2412631 INFO  (jetty-launcher-3812-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-11-29T11:14:09.627Z
   [junit4]   2> 2412637 INFO  (jetty-launcher-3812-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2412637 INFO  (jetty-launcher-3812-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2412650 WARN  (NIOServerCxn.Factory: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 0x158afcabde60002, 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> 2412650 WARN  (NIOServerCxn.Factory: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 0x158afcabde60001, 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> 2412655 INFO  (jetty-launcher-3812-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59667/solr
   [junit4]   2> 2412656 INFO  (jetty-launcher-3812-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59667/solr
   [junit4]   2> 2412691 INFO  (jetty-launcher-3812-thread-2) [n:127.0.0.1:59672_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59672_solr
   [junit4]   2> 2412691 INFO  (jetty-launcher-3812-thread-1) [n:127.0.0.1:59671_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59671_solr
   [junit4]   2> 2412692 INFO  (jetty-launcher-3812-thread-2) [n:127.0.0.1:59672_solr    ] o.a.s.c.Overseer Overseer (id=97020677292687365-127.0.0.1:59672_solr-n_0000000000) starting
   [junit4]   2> 2412694 INFO  (zkCallback-3822-thread-1-processing-n:127.0.0.1:59671_solr) [n:127.0.0.1:59671_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2412694 INFO  (zkCallback-3823-thread-1-processing-n:127.0.0.1:59672_solr) [n:127.0.0.1:59672_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2412704 INFO  (jetty-launcher-3812-thread-2) [n:127.0.0.1:59672_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59672_solr
   [junit4]   2> 2412707 INFO  (zkCallback-3822-thread-1-processing-n:127.0.0.1:59671_solr) [n:127.0.0.1:59671_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2412707 INFO  (zkCallback-3823-thread-1-processing-n:127.0.0.1:59672_solr) [n:127.0.0.1:59672_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2412746 INFO  (jetty-launcher-3812-thread-2) [n:127.0.0.1:59672_solr    ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin
   [junit4]   2> 2412803 INFO  (jetty-launcher-3812-thread-2) [n:127.0.0.1:59672_solr    ] o.a.s.s.KerberosPlugin Params: {token.valid=30, delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, cookie.path=/, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, token.validity=36000, signer.secret.provider.zookeeper.path=/token, type=kerberos, zk-dt-secret-manager.enable=true, kerberos.name.rules=DEFAULT, signer.secret.provider=zookeeper}
   [junit4]   2> 2412837 INFO  (jetty-launcher-3812-thread-1) [n:127.0.0.1:59671_solr    ] o.a.s.c.CoreContainer Initializing authentication plugin: org.apache.solr.security.HttpParamDelegationTokenPlugin
   [junit4]   2> 2412838 INFO  (jetty-launcher-3812-thread-1) [n:127.0.0.1:59671_solr    ] o.a.s.s.KerberosPlugin Params: {token.valid=30, delegation-token.token-kind=solr-dt, cookie.domain=127.0.0.1, cookie.path=/, zk-dt-secret-manager.znodeWorkingPath=solr/security/zkdtsm, token.validity=36000, signer.secret.provider.zookeeper.path=/token, type=kerberos, zk-dt-secret-manager.enable=true, kerberos.name.rules=DEFAULT, signer.secret.provider=zookeeper}
   [junit4]   2> 2413274 INFO  (jetty-launcher-3812-thread-1) [n:127.0.0.1:59671_solr    ] o.a.c.f.i.CuratorFrameworkImpl Starting
   [junit4]   2> 2413274 INFO  (jetty-launcher-3812-thread-2) [n:127.0.0.1:59672_solr    ] o.a.c.f.i.CuratorFrameworkImpl Starting
   [junit4]   2> 2413350 INFO  (jetty-launcher-3812-thread-1-EventThread) [n:127.0.0.1:59671_solr    ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
   [junit4]   2> 2413350 INFO  (jetty-launcher-3812-thread-2-EventThread) [n:127.0.0.1:59672_solr    ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
   [junit4]   2> 2413629 ERROR (jetty-launcher-3812-thread-2) [n:127.0.0.1:59672_solr    ] o.a.h.u.Shell Failed to locate the winutils binary in the hadoop binary path
   [junit4]   2> java.io.IOException: Could not locate executable null\bin\winutils.exe in the Hadoop binaries.
   [junit4]   2> 	at org.apache.hadoop.util.Shell.getQualifiedBinPath(Shell.java:356)
   [junit4]   2> 	at org.apache.hadoop.util.Shell.getWinUtilsPath(Shell.java:371)
   [junit4]   2> 	at org.apache.hadoop.util.Shell.<clinit>(Shell.java:364)
   [junit4]   2> 	at org.apache.hadoop.util.StringUtils.<clinit>(StringUtils.java:80)
   [junit4]   2> 	at org.apache.hadoop.conf.Configuration.getBoolean(Configuration.java:1437)
   [junit4]   2> 	at org.apache.hadoop.security.token.delegation.web.DelegationTokenManager.<init>(DelegationTokenManager.java:115)
   [junit4]   2> 	at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.initTokenManager(DelegationTokenAuthenticationHandler.java:148)
   [junit4]   2> 	at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationHandler.init(DelegationTokenAuthenticationHandler.java:118)
   [junit4]   2> 	at org.apache.solr.security.HttpParamDelegationTokenPlugin$HttpParamDelegationTokenAuthenticationHandler.init(HttpParamDelegationTokenPlugin.java:178)
   [junit4]   2> 	at org.apache.hadoop.security.authentication.server.AuthenticationFilter.initializeAuthHandler(AuthenticationFilter.java:238)
   [junit4]   2> 	at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationFilter.initializeAuthHandler(DelegationTokenAuthenticationFilter.java:209)
   [junit4]   2> 	at org.apache.solr.security.DelegationTokenKerberosFilter.initializeAuthHandler(DelegationTokenKerberosFilter.java:142)
   [junit4]   2> 	at org.apache.hadoop.security.authentication.server.AuthenticationFilter.init(AuthenticationFilter.java:227)
   [junit4]   2> 	at org.apache.hadoop.security.token.delegation.web.DelegationTokenAuthenticationFilter.init(DelegationTokenAuthenticationFilter.java:175)
   [junit4]   2> 	at org.apache.solr.security.DelegationTokenKerberosFilter.init(DelegationTokenKerberosFilter.java:71)
   [junit4]   2> 	at org.apache.solr.security.HttpParamDelegationTokenPlugin.init(HttpParamDelegationTokenPlugin.java:134)
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.initializeAuthenticationPlugin(CoreContainer.java:320)
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.reloadSecurityProperties(CoreContainer.java:577)
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.load(CoreContainer.java:467)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:204)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:153)
   [junit4]   2> 	at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:137)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:873)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1565)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1599)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1285)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1130)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:448)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:260)
   [junit4]   2> 	at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179)
   [junit4]   2> 	at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:348)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:321)
   [junit4]   2> 	at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:349)
   [junit4]   2> 	at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:212)
   [junit4]   2> 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
   [junit4]   2> 	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
   [junit4]   2> 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2413859 INFO  (jetty-launcher-3812-thread-2) [n:127.0.0.1:59672_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWithDelegationTokens_C423C2337603F98D-001\tempDir-001\node2\.
   [junit4]   2> 2413859 INFO  (jetty-launcher-3812-thread-1) [n:127.0.0.1:59671_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWithDelegationTokens_C423C2337603F98D-001\tempDir-001\node1\.
   [junit4]   2> 2413864 WARN  (NIOServerCxn.Factory: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 0x158afcabde60009, 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> 2413896 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel
   [junit4]   2> 2415462 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[C423C2337603F98D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2415464 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[C423C2337603F98D]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59667/solr ready
   [junit4]   2> 2415466 ERROR (qtp23720476-22176) [    ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_1]
   [junit4]   2> 2415498 WARN  (NIOServerCxn.Factory: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 0x158afcabde6000a, 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> 2415513 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2415523 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2415574 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[C423C2337603F98D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2415575 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[C423C2337603F98D]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59667/solr ready
   [junit4]   2> 2415577 ERROR (qtp23720476-22186) [    ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2]
   [junit4]   2> 2415582 WARN  (NIOServerCxn.Factory: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 0x158afcabde6000b, 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> 2415586 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2415588 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2415589 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel
   [junit4]   2> 2415627 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify
   [junit4]   2> 2415651 INFO  (qtp23720476-22177) [n:127.0.0.1:59672_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVivysoPigFY09dODwMEFO_IZP2h7-aciB1TiEHlwppeicOUB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2415651 INFO  (qtp23720476-22177) [n:127.0.0.1:59672_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVivysoPigFY09dODwMEFO_IZP2h7-aciB1TiEHlwppeicOUB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2415659 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2415663 INFO  (qtp9323303-22194) [n:127.0.0.1:59671_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVivysoPigFY09dODwMEFO_IZP2h7-aciB1TiEHlwppeicOUB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2415663 INFO  (qtp9323303-22194) [n:127.0.0.1:59671_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVivysoPigFY09dODwMEFO_IZP2h7-aciB1TiEHlwppeicOUB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2415668 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2415668 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify
   [junit4]   2> 2415714 INFO  (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths
   [junit4]   2> 2415730 INFO  (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths
   [junit4]   2> 2415763 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
   [junit4]   2> 2415806 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestSolrCloudWithDelegationTokens -Dtests.method=testDelegationTokenCancelFail -Dtests.seed=C423C2337603F98D -Dtests.slow=true -Dtests.locale=nl -Dtests.timezone=Kwajalein -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 0.08s J1 | TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<200> but was:<404>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([C423C2337603F98D:AC9CF719A699EB61]:0)
   [junit4]    > 	at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.cancelDelegationToken(TestSolrCloudWithDelegationTokens.java:140)
   [junit4]    > 	at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:294)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 2415851 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew
   [junit4]   2> 2415903 INFO  (qtp23720476-22176) [n:127.0.0.1:59672_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVivysrxigFY09dO8QYEFMMJQ4ZMJd1uw-onIsErwy6ZgUalB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2415903 INFO  (qtp23720476-22176) [n:127.0.0.1:59672_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVivysrxigFY09dO8QYEFMMJQ4ZMJd1uw-onIsErwy6ZgUalB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2415908 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2415917 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2415918 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59667/solr ready
   [junit4]   2> 2415920 INFO  (qtp23720476-22178) [n:127.0.0.1:59672_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVivysrxigFY09dO8QYEFMMJQ4ZMJd1uw-onIsErwy6ZgUalB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2415920 INFO  (qtp23720476-22178) [n:127.0.0.1:59672_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVivysrxigFY09dO8QYEFMMJQ4ZMJd1uw-onIsErwy6ZgUalB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2415920 WARN  (NIOServerCxn.Factory: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 0x158afcabde6000d, 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> 2415926 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2415927 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59667/solr ready
   [junit4]   2> 2415929 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2415930 WARN  (NIOServerCxn.Factory: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 0x158afcabde6000e, 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> 2415947 INFO  (qtp23720476-22197) [n:127.0.0.1:59672_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVivyss_igFY09dPPwcEFBk-j0hycdKfzZ_mpa_dGvvtvDfLB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2415947 INFO  (qtp23720476-22197) [n:127.0.0.1:59672_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVivyss_igFY09dPPwcEFBk-j0hycdKfzZ_mpa_dGvvtvDfLB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2415953 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2415954 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59667/solr ready
   [junit4]   2> 2415955 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2415957 WARN  (NIOServerCxn.Factory: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 0x158afcabde6000f, 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> 2415960 INFO  (qtp9323303-22179) [n:127.0.0.1:59671_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVivyss_igFY09dPPwcEFBk-j0hycdKfzZ_mpa_dGvvtvDfLB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2415960 INFO  (qtp9323303-22179) [n:127.0.0.1:59671_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVivyss_igFY09dPPwcEFBk-j0hycdKfzZ_mpa_dGvvtvDfLB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2415966 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2415966 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59667/solr ready
   [junit4]   2> 2415969 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2415969 WARN  (NIOServerCxn.Factory: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 0x158afcabde60010, 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> 2415981 INFO  (qtp23720476-22197) [n:127.0.0.1:59672_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVivystnigFY09dPZwgEFNOHsg5rXbJjMB5_mWv8Tqizr-dsB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2415981 INFO  (qtp23720476-22197) [n:127.0.0.1:59672_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVivystnigFY09dPZwgEFNOHsg5rXbJjMB5_mWv8Tqizr-dsB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2415985 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2415990 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2415991 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59667/solr ready
   [junit4]   2> 2415993 INFO  (qtp23720476-22177) [n:127.0.0.1:59672_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVivystnigFY09dPZwgEFNOHsg5rXbJjMB5_mWv8Tqizr-dsB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2415993 INFO  (qtp23720476-22177) [n:127.0.0.1:59672_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVivystnigFY09dPZwgEFNOHsg5rXbJjMB5_mWv8Tqizr-dsB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2415993 WARN  (NIOServerCxn.Factory: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 0x158afcabde60011, 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> 2415999 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2416002 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59667/solr ready
   [junit4]   2> 2416003 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2416004 WARN  (NIOServerCxn.Factory: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 0x158afcabde60012, 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> 2416017 INFO  (qtp23720476-22180) [n:127.0.0.1:59672_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVivysuJigFY09dPiQkEFL6eskpKzmUAjBY-_ZPiuOC3Qw1gB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2416017 INFO  (qtp23720476-22180) [n:127.0.0.1:59672_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVivysuJigFY09dPiQkEFL6eskpKzmUAjBY-_ZPiuOC3Qw1gB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2416019 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2416026 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2416027 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59667/solr ready
   [junit4]   2> 2416027 INFO  (qtp23720476-22195) [n:127.0.0.1:59672_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVivysuJigFY09dPiQkEFL6eskpKzmUAjBY-_ZPiuOC3Qw1gB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2416027 INFO  (qtp23720476-22195) [n:127.0.0.1:59672_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVivysuJigFY09dPiQkEFL6eskpKzmUAjBY-_ZPiuOC3Qw1gB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2416028 WARN  (NIOServerCxn.Factory: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 0x158afcabde60013, 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> 2416032 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2416032 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew
   [junit4]   2> 2416063 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient
   [junit4]   2> 2416070 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2416075 INFO  (qtp23720476-22180) [n:127.0.0.1:59672_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVivysvDigFY09dPwwoEFKeIC6mCh1mBDVYhUXBbQ66IprdIB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2416075 INFO  (qtp23720476-22180) [n:127.0.0.1:59672_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVivysvDigFY09dPwwoEFKeIC6mCh1mBDVYhUXBbQ66IprdIB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2416075 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient
   [junit4]   2> 2416116 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp
   [junit4]   2> 2416119 INFO  (qtp23720476-22177) [n:127.0.0.1:59672_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params op=someSolrOperation&action=LIST&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2416119 INFO  (qtp23720476-22177) [n:127.0.0.1:59672_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={op=someSolrOperation&action=LIST&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 2416121 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=bar&p=bar&t=test&e=1480454053114&s=spIZnAgH3PNrqY3MufQxTF2nVMw="; Path=/; Domain=127.0.0.1; Expires=di, 29-nov-2016 21:14:13 GMT; HttpOnly". Invalid 'expires' attribute: di, 29-nov-2016 21:14:13 GMT
   [junit4]   2> 2416121 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp
   [junit4]   2> 2416166 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail
   [junit4]   2> 2416171 WARN  (qtp23720476-22195) [n:127.0.0.1:59672_solr    ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: foo tries to renew a token with renewer bar
   [junit4]   2> 2416171 WARN  (qtp23720476-22195) [n:127.0.0.1:59672_solr    ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
   [junit4]   2> 2416172 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2416172 WARN  (qtp9323303-22187) [    ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: foo tries to renew a token with renewer bar
   [junit4]   2> 2416172 WARN  (qtp9323303-22187) [    ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
   [junit4]   2> 2416173 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2416177 WARN  (qtp23720476-22197) [n:127.0.0.1:59672_solr    ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew a token with renewer renewUser
   [junit4]   2> 2416178 WARN  (qtp23720476-22197) [n:127.0.0.1:59672_solr    ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
   [junit4]   2> 2416178 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2416179 WARN  (qtp9323303-22187) [    ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: notRenewUser tries to renew a token with renewer renewUser
   [junit4]   2> 2416179 WARN  (qtp9323303-22187) [    ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
   [junit4]   2> 2416179 WARN  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[C423C2337603F98D]) [    ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=do, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: do, 01-jan-1970 00:00:00 GMT
   [junit4]   2> 2416179 INFO  (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail
   [junit4]   2> 2416183 INFO  (jetty-launcher-3812-thread-1) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@41ad5{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2416184 INFO  (jetty-launcher-3812-thread-1) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=31426126
   [junit4]   2> 2416187 INFO  (jetty-launcher-3812-thread-2) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@16e9e54{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2416188 INFO  (jetty-launcher-3812-thread-2) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=31586495
   [junit4]   2> 2416194 INFO  (jetty-launcher-3812-thread-1) [    ] o.a.s.c.Overseer Overseer (id=97020677292687365-127.0.0.1:59672_solr-n_0000000000) closing
   [junit4]   2> 2416195 INFO  (OverseerStateUpdate-97020677292687365-127.0.0.1:59672_solr-n_0000000000) [n:127.0.0.1:59672_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:59672_solr
   [junit4]   2> 2416199 ERROR (Thread[Thread-4935,5,TGRP-TestSolrCloudWithDelegationTokens]) [n:127.0.0.1:59671_solr    ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
   [junit4]   2> 2416200 ERROR (Thread[Thread-4934,5,TGRP-TestSolrCloudWithDelegationTokens]) [n:127.0.0.1:59672_solr    ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
   [junit4]   2> 2416234 WARN  (NIOServerCxn.Factory: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 0x158afcabde60007, 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> 2416234 INFO  (jetty-launcher-3812-thread-2) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@bd2018{/solr,null,UNAVAILABLE}
   [junit4]   2> 2416235 INFO  (jetty-launcher-3812-thread-1) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1f27a72{/solr,null,UNAVAILABLE}
   [junit4]   2> 2416239 INFO  (SUITE-TestSolrCloudWithDelegationTokens-seed#[C423C2337603F98D]-worker) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59667 59667
   [junit4]   2> 2416242 INFO  (Thread-4911) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:59667 59667
   [junit4]   2> 2416243 WARN  (Thread-4911) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	11	/solr/aliases.json
   [junit4]   2> 	11	/solr/clusterprops.json
   [junit4]   2> 	2	/solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	11	/solr/clusterstate.json
   [junit4]   2> 	4	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMKeyIdRoot
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_7
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_6
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_9
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_8
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_1
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_1
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_3
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_3
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_2
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_2
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_5
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot/DK_4
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_4
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_12
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMSeqNumRoot
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_11
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_10
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	11	/solr/live_nodes
   [junit4]   2> 	11	/solr/collections
   [junit4]   2> 	3	/solr/overseer/queue
   [junit4]   2> 	3	/solr/overseer/collection-queue-work
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMMasterKeyRoot
   [junit4]   2> 	2	/solr/overseer/queue-work
   [junit4]   2> 	2	/solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot
   [junit4]   2> 
   [junit4]   2> 2416244 INFO  (SUITE-TestSolrCloudWithDelegationTokens-seed#[C423C2337603F98D]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWithDelegationTokens_C423C2337603F98D-001
   [junit4]   2> Nov 29, 2016 11:14:13 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 5 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=false): {}, locale=nl, timezone=Kwajalein
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_102 (32-bit)/cpus=3,threads=1,free=252153384,total=466616320
   [junit4]   2> NOTE: All tests run in this JVM: [TestConfigSetImmutable, CoreMergeIndexesAdminHandlerTest, PeerSyncReplicationTest, SpellingQueryConverterTest, CdcrReplicationHandlerTest, BasicZkTest, TestSolrQueryResponse, TestDFRSimilarityFactory, TestDistributedMissingSort, TestSubQueryTransformer, TestReversedWildcardFilterFactory, CircularListTest, DistributedFacetPivotSmallAdvancedTest, DocValuesMissingTest, TestSolrConfigHandlerCloud, TestBackupRepositoryFactory, FieldAnalysisRequestHandlerTest, SimpleMLTQParserTest, BufferStoreTest, TestLuceneMatchVersion, TestLeaderElectionZkExpiry, TestNoOpRegenerator, TestMacros, DistributedTermsComponentTest, HdfsDirectoryFactoryTest, AnalyticsQueryTest, TestSizeLimitedDistributedMap, TestJsonRequest, TestTolerantUpdateProcessorCloud, BitVectorTest, ScriptEngineTest, DateMathParserTest, TestAtomicUpdateErrorCases, ShowFileRequestHandlerTest, JsonLoaderTest, TestOmitPositions, DistribCursorPagingTest, DataDrivenBlockJoinTest, TestManagedResourceStorage, TestHdfsUpdateLog, TestJavabinTupleStreamParser, BigEndianAscendingWordDeserializerTest, TestSolrXml, ForceLeaderTest, TestBlobHandler, TestUtils, MultiThreadedOCPTest, TestEmbeddedSolrServerConstructors, ZkSolrClientTest, BasicAuthStandaloneTest, TestCollationFieldDocValues, TimerUtilsTest, TestLocalFSCloudBackupRestore, TermVectorComponentDistributedTest, TestSuggestSpellingConverter, FacetPivotSmallTest, TestQuerySenderListener, ResourceLoaderTest, TestDefaultStatsCache, SuggesterTSTTest, WordBreakSolrSpellCheckerTest, DeleteInactiveReplicaTest, TestValueSourceCache, TestSchemaNameResource, StatsComponentTest, ConnectionReuseTest, ExternalFileFieldSortTest, ChangedSchemaMergeTest, CSVRequestHandlerTest, BasicDistributedZk2Test, TestFuzzyAnalyzedSuggestions, TestDFISimilarityFactory, BasicFunctionalityTest, AliasIntegrationTest, TestBulkSchemaAPI, TestConfig, TestReqParamsAPI, TestSchemaSimilarityResource, CacheHeaderTest, TestFieldCache, CoreAdminCreateDiscoverTest, TestCoreDiscovery, TestLRUStatsCache, OverseerStatusTest, CdcrBootstrapTest, WrapperMergePolicyFactoryTest, ClassificationUpdateProcessorTest, HttpSolrCallGetCoreTest, SortByFunctionTest, SortSpecParsingTest, CachingDirectoryFactoryTest, TestRawResponseWriter, LeaderElectionContextKeyTest, BlockCacheTest, TestSmileRequest, TestJsonFacetRefinement, URLClassifyProcessorTest, DocExpirationUpdateProcessorFactoryTest, TestRebalanceLeaders, TestRequestForwarding, DistributedIntervalFacetingTest, HdfsRecoveryZkTest, SuggesterWFSTTest, TestTestInjection, TestMiniSolrCloudClusterSSL, FullSolrCloudDistribCmdsTest, TestDynamicFieldCollectionResource, ReturnFieldsTest, PreAnalyzedFieldTest, CoreAdminHandlerTest, ParsingFieldUpdateProcessorsTest, BlobRepositoryCloudTest, NoCacheHeaderTest, DistanceFunctionTest, CollectionReloadTest, HdfsSyncSliceTest, TestBadConfig, RollingRestartTest, CreateCollectionCleanupTest, TestScoreJoinQPNoScore, SuggestComponentContextFilterQueryTest, DistributedFacetPivotLargeTest, ClassificationUpdateProcessorFactoryTest, TestAuthorizationFramework, TestReloadAndDeleteDocs, BlockJoinFacetDistribTest, TestBlendedInfixSuggestions, HdfsTlogReplayBufferedWhileIndexingTest, TestStressUserVersions, TestFieldCollectionResource, RAMDirectoryFactoryTest, DirectoryFactoryTest, TestAnalyzedSuggestions, TestSolr4Spatial, TestFastWriter, SaslZkACLProviderTest, OverseerModifyCollectionTest, LeaderInitiatedRecoveryOnShardRestartTest, TestConfigSetsAPI, DebugComponentTest, TestJmxMonitoredMap, AsyncCallRequestStatusResponseTest, SpellCheckComponentTest, TestCustomDocTransformer, UpdateParamsTest, TestSubQueryTransformerDistrib, TestFoldingMultitermQuery, TestDistributedGrouping, TestSerializedLuceneMatchVersion, TestIndexSearcher, TestDocSet, TestUseDocValuesAsStored2, TestLMJelinekMercerSimilarityFactory, BadCopyFieldTest, SpellPossibilityIteratorTest, TestZkChroot, TestRandomFlRTGCloud, TestTrie, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, RecoveryZkTest, TestRandomFaceting, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, AutoCommitTest, TestFaceting, TestRecovery, TestHashPartitioner, DistributedSpellCheckComponentTest, TestRealTimeGet, TestJoin, TestReload, TestStressVersions, TestRangeQuery, TestCoreContainer, SolrCoreTest, SolrCmdDistributorTest, QueryElevationComponentTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFiltering, TestFunctionQuery, DirectUpdateHandlerTest, HighlighterTest, CurrencyFieldOpenExchangeTest, SolrIndexSplitterTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SpellCheckCollatorTest, SolrRequestParserTest, SuggesterTest, TestStressLucene, TestCSVLoader, SolrCoreCheckLockOnStartupTest, TestUpdate, FieldMutatingUpdateProcessorTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, DocValuesMultiTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, TestQueryUtils, StandardRequestHandlerTest, TestWriterPerf, PrimitiveFieldTypeTest, FileBasedSpellCheckerTest, RequestHandlersTest, TermVectorComponentTest, TestSolrQueryParser, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, IndexSchemaTest, TestCollationField, UpdateRequestProcessorFactoryTest, MBeansHandlerTest, UniqFieldsUpdateProcessorFactoryTest, TestComponentsName, SOLR749Test, TestSolrIndexConfig, ResponseLogComponentTest, TestStressRecovery, TestSolrDeletionPolicy2, MultiTermTest, SampleTest, TestBinaryField, MinimalSchemaTest, OutputWriterTest, NotRequiredUniqueKeyTest, TestCharFilters, EchoParamsTest, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TimeZoneUtilsTest, OpenExchangeRatesOrgProviderTest, TestFastLRUCache, DOMUtilTest, ClusterStateTest, TestDocumentBuilder, UUIDFieldTest, CursorPagingTest, TestJettySolrRunner, ActionThrottleTest, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, CollectionsAPISolrJTest, DeleteShardTest, DocValuesNotIndexedTest, LeaderFailoverAfterPartitionTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, RemoteQueryErrorTest, SolrCloudExampleTest, TestClusterProperties, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestDeleteCollectionOnDownNodes, TestDistribDocBasedVersion, TestExclusionRuleCollectionAccess, TestLockTree, TestMiniSolrCloudCluster, TestSolrCloudWithDelegationTokens]
   [junit4] Completed [506/658 (1!)] on J1 in 4.53s, 8 tests, 1 failure <<< FAILURES!

[...truncated 505 lines...]
   [junit4] Suite: org.apache.solr.util.TestSolrCLIRunExample
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\init-core-data-001
   [junit4]   2> 2725968 INFO  (SUITE-TestSolrCLIRunExample-seed#[C423C2337603F98D]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
   [junit4]   2> 2725971 INFO  (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[C423C2337603F98D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testInteractiveSolrCloudExample
   [junit4]   2> 2728100 INFO  (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[C423C2337603F98D]) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002
   [junit4]   2> 2728101 INFO  (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[C423C2337603F98D]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2728101 INFO  (Thread-5974) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2728101 INFO  (Thread-5974) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2728201 INFO  (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[C423C2337603F98D]) [    ] o.a.s.c.ZkTestServer start zk server on port:62054
   [junit4]   2> 2728219 INFO  (jetty-launcher-4535-thread-1) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2728221 INFO  (jetty-launcher-4535-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@473058{/solr,null,AVAILABLE}
   [junit4]   2> 2728222 INFO  (jetty-launcher-4535-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@4e382f{HTTP/1.1,[http/1.1]}{127.0.0.1:62049}
   [junit4]   2> 2728224 INFO  (jetty-launcher-4535-thread-1) [    ] o.e.j.s.Server Started @2732876ms
   [junit4]   2> 2728224 INFO  (jetty-launcher-4535-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62049}
   [junit4]   2> 2728224 ERROR (jetty-launcher-4535-thread-1) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2728224 INFO  (jetty-launcher-4535-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 7.0.0
   [junit4]   2> 2728225 INFO  (jetty-launcher-4535-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port 62049
   [junit4]   2> 2728225 INFO  (jetty-launcher-4535-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2728225 INFO  (jetty-launcher-4535-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-11-29T11:19:25.221Z
   [junit4]   2> 2728231 INFO  (jetty-launcher-4535-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2728254 INFO  (jetty-launcher-4535-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62054/solr
   [junit4]   2> 2728301 INFO  (jetty-launcher-4535-thread-1) [n:localhost:62049_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader localhost:62049_solr
   [junit4]   2> 2728302 INFO  (jetty-launcher-4535-thread-1) [n:localhost:62049_solr    ] o.a.s.c.Overseer Overseer (id=97020697975324675-localhost:62049_solr-n_0000000000) starting
   [junit4]   2> 2728312 INFO  (jetty-launcher-4535-thread-1) [n:localhost:62049_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/localhost:62049_solr
   [junit4]   2> 2728316 INFO  (OverseerStateUpdate-97020697975324675-localhost:62049_solr-n_0000000000) [n:localhost:62049_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2728523 INFO  (jetty-launcher-4535-thread-1) [n:localhost:62049_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1
   [junit4]   2> 2731549 INFO  (qtp22390573-26200) [n:localhost:62049_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=14
   [junit4]   2> 2731551 INFO  (qtp22390573-26205) [n:localhost:62049_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
   [junit4]   2> 2731552 INFO  (qtp22390573-26205) [n:localhost:62049_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=1
   [junit4]   2> 2731558 INFO  (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[C423C2337603F98D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2731559 INFO  (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[C423C2337603F98D]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62054/solr ready
   [junit4]   2> 2731562 INFO  (qtp22390573-26204) [n:localhost:62049_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
   [junit4]   2> 2731562 INFO  (qtp22390573-26204) [n:localhost:62049_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
   [junit4]   2> 2731571 INFO  (qtp22390573-26205) [n:localhost:62049_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/system params={wt=json} status=0 QTime=5
   [junit4]   2> 2731573 INFO  (qtp22390573-26203) [n:localhost:62049_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterstatus with params action=CLUSTERSTATUS&wt=json and sendToOCPQueue=true
   [junit4]   2> 2731573 INFO  (qtp22390573-26203) [n:localhost:62049_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=CLUSTERSTATUS&wt=json} status=0 QTime=0
   [junit4]   2> 2731579 INFO  (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[C423C2337603F98D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2731580 INFO  (TEST-TestSolrCLIRunExample.testInteractiveSolrCloudExample-seed#[C423C2337603F98D]) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62054/solr ready
   [junit4]   2> 2731689 INFO  (qtp22390573-26201) [n:localhost:62049_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=list&wt=json and sendToOCPQueue=true
   [junit4]   2> 2731689 INFO  (qtp22390573-26201) [n:localhost:62049_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=list&wt=json} status=0 QTime=0
   [junit4]   2> 2731691 INFO  (qtp22390573-26205) [n:localhost:62049_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&maxShardsPerNode=4&collection.configName=testCloudExamplePrompt&name=testCloudExamplePrompt&action=CREATE&numShards=2&wt=json and sendToOCPQueue=true
   [junit4]   2> 2731693 INFO  (OverseerThreadFactory-12359-thread-1-processing-n:localhost:62049_solr) [n:localhost:62049_solr    ] o.a.s.c.CreateCollectionCmd Create collection testCloudExamplePrompt
   [junit4]   2> 2731693 WARN  (OverseerThreadFactory-12359-thread-1-processing-n:localhost:62049_solr) [n:localhost:62049_solr    ] o.a.s.c.CreateCollectionCmd Specified replicationFactor of 2 on collection testCloudExamplePrompt is higher than or equal to the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
   [junit4]   2> 2731800 INFO  (qtp22390573-26204) [n:localhost:62049_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard1_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
   [junit4]   2> 2731800 INFO  (qtp22390573-26203) [n:localhost:62049_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard2_replica2&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
   [junit4]   2> 2731800 INFO  (qtp22390573-26202) [n:localhost:62049_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard1_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard1&wt=javabin&version=2
   [junit4]   2> 2731802 INFO  (qtp22390573-26201) [n:localhost:62049_solr    ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=testCloudExamplePrompt&newCollection=true&name=testCloudExamplePrompt_shard2_replica1&action=CREATE&numShards=2&collection=testCloudExamplePrompt&shard=shard2&wt=javabin&version=2
   [junit4]   2> 2731914 INFO  (zkCallback-4541-thread-3-processing-n:localhost:62049_solr) [n:localhost:62049_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testCloudExamplePrompt/state.json] for collection [testCloudExamplePrompt] has occurred - updating... (live nodes size: [1])
   [junit4]   2> 2732829 INFO  (qtp22390573-26204) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0 
   [junit4]   2> 2732829 INFO  (qtp22390573-26201) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0 
   [junit4]   2> 2732830 INFO  (qtp22390573-26204) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.RequestParams request params refreshed to version 0
   [junit4]   2> 2732830 INFO  (qtp22390573-26201) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.RequestParams request params refreshed to version 0
   [junit4]   2> 2732829 INFO  (qtp22390573-26202) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0 
   [junit4]   2> 2732832 INFO  (qtp22390573-26202) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.RequestParams request params refreshed to version 0
   [junit4]   2> 2732834 WARN  (qtp22390573-26201) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\extraction\lib
   [junit4]   2> 2732834 WARN  (qtp22390573-26202) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\extraction\lib
   [junit4]   2> 2732836 WARN  (qtp22390573-26201) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist
   [junit4]   2> 2732836 WARN  (qtp22390573-26204) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica2\..\..\..\..\contrib\extraction\lib
   [junit4]   2> 2732838 WARN  (qtp22390573-26201) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\clustering\lib
   [junit4]   2> 2732836 WARN  (qtp22390573-26202) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist filtered by solr-cell-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist
   [junit4]   2> 2732838 WARN  (qtp22390573-26201) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist
   [junit4]   2> 2732839 WARN  (qtp22390573-26202) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\clustering\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\clustering\lib
   [junit4]   2> 2732839 WARN  (qtp22390573-26202) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist filtered by solr-clustering-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist
   [junit4]   2> 2732839 WARN  (qtp22390573-26201) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\langid\lib
   [junit4]   2> 2732839 WARN  (qtp22390573-26201) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist
   [junit4]   2> 2732840 WARN  (qtp22390573-26202) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\langid\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\langid\lib
   [junit4]   2> 2732840 WARN  (qtp22390573-26202) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist filtered by solr-langid-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist
   [junit4]   2> 2732840 WARN  (qtp22390573-26201) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\contrib\velocity\lib
   [junit4]   2> 2732841 WARN  (qtp22390573-26202) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\velocity\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\contrib\velocity\lib
   [junit4]   2> 2732841 WARN  (qtp22390573-26201) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica1\..\..\..\..\dist
   [junit4]   2> 2732841 WARN  (qtp22390573-26202) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard1  x:testCloudExamplePrompt_shard1_replica1] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist filtered by solr-velocity-\d.*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard1_replica1\..\..\..\..\dist
   [junit4]   2> 2732841 INFO  (qtp22390573-26203) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams conf resource params.json loaded . version : 0 
   [junit4]   2> 2732842 INFO  (qtp22390573-26203) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.RequestParams request params refreshed to version 0
   [junit4]   2> 2732843 WARN  (qtp22390573-26203) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica2] o.a.s.c.SolrConfig Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\contrib\extraction\lib filtered by .*\.jar to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001\tempDir-002\node1\testCloudExamplePrompt_shard2_replica2\..\..\..\..\contrib\extraction\lib
   [junit4]   2> 2732847 INFO  (qtp22390573-26201) [n:localhost:62049_solr c:testCloudExamplePrompt s:shard2  x:testCloudExamplePrompt_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2732847 INFO  (qtp22390573-26202) [n:l

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

indows\solr\build\solr-core\test\J1\temp\solr.util.TestSolrCLIRunExample_C423C2337603F98D-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=355, maxMBSortInHeap=7.51986145203373, sim=RandomSimilarity(queryNorm=false): {}, locale=es-US, timezone=Africa/Kigali
   [junit4]   2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_102 (32-bit)/cpus=3,threads=1,free=325475776,total=531628032
   [junit4]   2> NOTE: All tests run in this JVM: [TestConfigSetImmutable, CoreMergeIndexesAdminHandlerTest, PeerSyncReplicationTest, SpellingQueryConverterTest, CdcrReplicationHandlerTest, BasicZkTest, TestSolrQueryResponse, TestDFRSimilarityFactory, TestDistributedMissingSort, TestSubQueryTransformer, TestReversedWildcardFilterFactory, CircularListTest, DistributedFacetPivotSmallAdvancedTest, DocValuesMissingTest, TestSolrConfigHandlerCloud, TestBackupRepositoryFactory, FieldAnalysisRequestHandlerTest, SimpleMLTQParserTest, BufferStoreTest, TestLuceneMatchVersion, TestLeaderElectionZkExpiry, TestNoOpRegenerator, TestMacros, DistributedTermsComponentTest, HdfsDirectoryFactoryTest, AnalyticsQueryTest, TestSizeLimitedDistributedMap, TestJsonRequest, TestTolerantUpdateProcessorCloud, BitVectorTest, ScriptEngineTest, DateMathParserTest, TestAtomicUpdateErrorCases, ShowFileRequestHandlerTest, JsonLoaderTest, TestOmitPositions, DistribCursorPagingTest, DataDrivenBlockJoinTest, TestManagedResourceStorage, TestHdfsUpdateLog, TestJavabinTupleStreamParser, BigEndianAscendingWordDeserializerTest, TestSolrXml, ForceLeaderTest, TestBlobHandler, TestUtils, MultiThreadedOCPTest, TestEmbeddedSolrServerConstructors, ZkSolrClientTest, BasicAuthStandaloneTest, TestCollationFieldDocValues, TimerUtilsTest, TestLocalFSCloudBackupRestore, TermVectorComponentDistributedTest, TestSuggestSpellingConverter, FacetPivotSmallTest, TestQuerySenderListener, ResourceLoaderTest, TestDefaultStatsCache, SuggesterTSTTest, WordBreakSolrSpellCheckerTest, DeleteInactiveReplicaTest, TestValueSourceCache, TestSchemaNameResource, StatsComponentTest, ConnectionReuseTest, ExternalFileFieldSortTest, ChangedSchemaMergeTest, CSVRequestHandlerTest, BasicDistributedZk2Test, TestFuzzyAnalyzedSuggestions, TestDFISimilarityFactory, BasicFunctionalityTest, AliasIntegrationTest, TestBulkSchemaAPI, TestConfig, TestReqParamsAPI, TestSchemaSimilarityResource, CacheHeaderTest, TestFieldCache, CoreAdminCreateDiscoverTest, TestCoreDiscovery, TestLRUStatsCache, OverseerStatusTest, CdcrBootstrapTest, WrapperMergePolicyFactoryTest, ClassificationUpdateProcessorTest, HttpSolrCallGetCoreTest, SortByFunctionTest, SortSpecParsingTest, CachingDirectoryFactoryTest, TestRawResponseWriter, LeaderElectionContextKeyTest, BlockCacheTest, TestSmileRequest, TestJsonFacetRefinement, URLClassifyProcessorTest, DocExpirationUpdateProcessorFactoryTest, TestRebalanceLeaders, TestRequestForwarding, DistributedIntervalFacetingTest, HdfsRecoveryZkTest, SuggesterWFSTTest, TestTestInjection, TestMiniSolrCloudClusterSSL, FullSolrCloudDistribCmdsTest, TestDynamicFieldCollectionResource, ReturnFieldsTest, PreAnalyzedFieldTest, CoreAdminHandlerTest, ParsingFieldUpdateProcessorsTest, BlobRepositoryCloudTest, NoCacheHeaderTest, DistanceFunctionTest, CollectionReloadTest, HdfsSyncSliceTest, TestBadConfig, RollingRestartTest, CreateCollectionCleanupTest, TestScoreJoinQPNoScore, SuggestComponentContextFilterQueryTest, DistributedFacetPivotLargeTest, ClassificationUpdateProcessorFactoryTest, TestAuthorizationFramework, TestReloadAndDeleteDocs, BlockJoinFacetDistribTest, TestBlendedInfixSuggestions, HdfsTlogReplayBufferedWhileIndexingTest, TestStressUserVersions, TestFieldCollectionResource, RAMDirectoryFactoryTest, DirectoryFactoryTest, TestAnalyzedSuggestions, TestSolr4Spatial, TestFastWriter, SaslZkACLProviderTest, OverseerModifyCollectionTest, LeaderInitiatedRecoveryOnShardRestartTest, TestConfigSetsAPI, DebugComponentTest, TestJmxMonitoredMap, AsyncCallRequestStatusResponseTest, SpellCheckComponentTest, TestCustomDocTransformer, UpdateParamsTest, TestSubQueryTransformerDistrib, TestFoldingMultitermQuery, TestDistributedGrouping, TestSerializedLuceneMatchVersion, TestIndexSearcher, TestDocSet, TestUseDocValuesAsStored2, TestLMJelinekMercerSimilarityFactory, BadCopyFieldTest, SpellPossibilityIteratorTest, TestZkChroot, TestRandomFlRTGCloud, TestTrie, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, RecoveryZkTest, TestRandomFaceting, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, AutoCommitTest, TestFaceting, TestRecovery, TestHashPartitioner, DistributedSpellCheckComponentTest, TestRealTimeGet, TestJoin, TestReload, TestStressVersions, TestRangeQuery, TestCoreContainer, SolrCoreTest, SolrCmdDistributorTest, QueryElevationComponentTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFiltering, TestFunctionQuery, DirectUpdateHandlerTest, HighlighterTest, CurrencyFieldOpenExchangeTest, SolrIndexSplitterTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SpellCheckCollatorTest, SolrRequestParserTest, SuggesterTest, TestStressLucene, TestCSVLoader, SolrCoreCheckLockOnStartupTest, TestUpdate, FieldMutatingUpdateProcessorTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, DocValuesMultiTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, TestQueryUtils, StandardRequestHandlerTest, TestWriterPerf, PrimitiveFieldTypeTest, FileBasedSpellCheckerTest, RequestHandlersTest, TermVectorComponentTest, TestSolrQueryParser, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, IndexSchemaTest, TestCollationField, UpdateRequestProcessorFactoryTest, MBeansHandlerTest, UniqFieldsUpdateProcessorFactoryTest, TestComponentsName, SOLR749Test, TestSolrIndexConfig, ResponseLogComponentTest, TestStressRecovery, TestSolrDeletionPolicy2, MultiTermTest, SampleTest, TestBinaryField, MinimalSchemaTest, OutputWriterTest, NotRequiredUniqueKeyTest, TestCharFilters, EchoParamsTest, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TimeZoneUtilsTest, OpenExchangeRatesOrgProviderTest, TestFastLRUCache, DOMUtilTest, ClusterStateTest, TestDocumentBuilder, UUIDFieldTest, CursorPagingTest, TestJettySolrRunner, ActionThrottleTest, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, CollectionsAPISolrJTest, DeleteShardTest, DocValuesNotIndexedTest, LeaderFailoverAfterPartitionTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, RemoteQueryErrorTest, SolrCloudExampleTest, TestClusterProperties, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestDeleteCollectionOnDownNodes, TestDistribDocBasedVersion, TestExclusionRuleCollectionAccess, TestLockTree, TestMiniSolrCloudCluster, TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, TlogReplayBufferedWhileIndexingTest, VMParamsZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZkTest, HdfsChaosMonkeySafeLeaderTest, HdfsCollectionsAPIDistributedZkTest, HdfsNNFailoverTest, HdfsRestartWhileUpdatingTest, HdfsThreadLeakTest, HdfsWriteToMultipleCollectionsTest, StressHdfsTest, TestClusterStateMutator, ZkStateReaderTest, RuleEngineTest, ExitableDirectoryReaderTest, TestConfigSets, TestCorePropertiesReload, TestDynamicLoading, TestSolrDynamicMBean, TestSolrCoreSnapshots, TestConfigReload, TestSolrConfigHandlerConcurrent, DistributedFacetPivotWhiteBoxTest, DistributedSuggestComponentTest, SuggestComponentTest, TestDistributedStatsComponentCardinality, JavabinLoaderTest, TestUnifiedSolrHighlighter, TestIntervalFaceting, TestMacroExpander, SmileWriterTest, TestBinaryResponseWriter, TestExportWriter, TestGeoJSONResponseWriter, TestPushWriter, TestRawTransformer, TestSubQueryTransformerCrossCore, TestManagedResource, TestRestManager, TestDefaultSearchFieldResource, TestFieldTypeCollectionResource, TestSolrQueryParserDefaultOperatorResource, TestManagedStopFilterFactory, EnumFieldTest, PreAnalyzedFieldManagedSchemaCloudTest, TestCloudManagedSchema, TestManagedSchema, TestManagedSchemaAPI, TestManagedSchemaThreadSafety, TestSchemaManager, AnalyticsMergeStrategyTest, MergeStrategyTest, TestComplexPhraseQParserPlugin, TestFieldSortValues, TestGraphTermsQParserPlugin, TestInitQParser, TestLegacyNumericRangeQueryBuilder, TestMaxScoreQueryParser, TestMissingGroups, TestOverriddenPrefixQueryForCustomFieldType, TestQueryWrapperFilter, TestRandomCollapseQParserPlugin, TestRecoveryHdfs, TestSearcherReuse, TestSimpleQParserPlugin, TestSolr4Spatial2, TestSolrFieldCacheMBean, TestStandardQParsers, TestXmlQParser, TestMinMaxOnMultiValuedField, TestOrdValues, TestSortByMinMaxFunction, BJQParserTest, BlockJoinFacetRandomTest, BlockJoinFacetSimpleTest, GraphQueryTest, CloudMLTQParserTest, TestClassicSimilarityFactory, TestNonDefinedSimilarityFactory, TestPerFieldSimilarityWithDefaultOverride, TestExactSharedStatsCache, TestPKIAuthenticationPlugin, TestRuleBasedAuthorizationPlugin, TestSha256AuthenticationProvider, ResponseHeaderTest, ConjunctionSolrSpellCheckerTest, TestFileDictionaryLookup, TestFreeTextSuggestions, HdfsLockFactoryTest, TestDocTermOrds, TestFieldCacheReopen, TestFieldCacheSanityChecker, TestFieldCacheSort, TestFieldCacheSortRandom, TestFieldCacheWithThreads, TestNumericTerms32, TestNumericTerms64, TestUninvertingReader, AddBlockUpdateTest, AnalysisErrorHandlingTest, CdcrUpdateLogTest, PeerSyncWithIndexFingerprintCachingTest, TestDocBasedVersionConstraints, AddSchemaFieldsUpdateProcessorFactoryTest, ClassificationUpdateProcessorIntegrationTest, TemplateUpdateProcessorTest, TolerantUpdateProcessorTest, DistanceUnitsTest, TestRTimerTree, TestSolrCLIRunExample]
   [junit4] Completed [651/658 (2!)] on J1 in 15.39s, 3 tests, 1 failure, 1 skipped <<< FAILURES!

[...truncated 54538 lines...]


[JENKINS] Lucene-Solr-master-Windows (64bit/jdk1.8.0_102) - Build # 6256 - Still Unstable!

Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6256/
Java: 64bit/jdk1.8.0_102 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication

Error Message:
timed out waiting for collection1 startAt time to exceed: Tue Nov 29 16:42:43 AST 2016

Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Tue Nov 29 16:42:43 AST 2016
	at __randomizedtesting.SeedInfo.seed([7FC99266A7F8801B:88BA7C3E61102FFD]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1508)
	at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1314)
	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:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	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:811)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	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)




Build Log:
[...truncated 11123 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\init-core-data-001
   [junit4]   2> 505777 INFO  (SUITE-TestReplicationHandler-seed#[7FC99266A7F8801B]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
   [junit4]   2> 505777 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterStartupWithNoActivity
   [junit4]   2> 505777 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\collection1
   [junit4]   2> 505793 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 505793 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7f8f1e2f{/solr,null,AVAILABLE}
   [junit4]   2> 505809 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@148fa274{HTTP/1.1,[http/1.1]}{127.0.0.1:61778}
   [junit4]   2> 505809 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.Server Started @510892ms
   [junit4]   2> 505809 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=61778}
   [junit4]   2> 505809 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 505840 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solrâ„¢ version 7.0.0
   [junit4]   2> 505840 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 505840 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 505840 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-11-29T20:41:34.983Z
   [junit4]   2> 505840 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\solr.xml
   [junit4]   2> 505871 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\.
   [junit4]   2> 505871 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 505902 INFO  (coreLoadExecutor-1857-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 505902 INFO  (coreLoadExecutor-1857-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 505918 INFO  (coreLoadExecutor-1857-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 505918 INFO  (coreLoadExecutor-1857-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\.\collection1
   [junit4]   2> 505918 INFO  (coreLoadExecutor-1857-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\.\collection1\data\]
   [junit4]   2> 505965 INFO  (coreLoadExecutor-1857-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=54.1279296875, floorSegmentMB=1.9638671875, forceMergeDeletesPctAllowed=1.5514710995267544, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7324973929379563
   [junit4]   2> 505981 INFO  (coreLoadExecutor-1857-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 505981 INFO  (coreLoadExecutor-1857-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 505981 INFO  (coreLoadExecutor-1857-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 505981 INFO  (coreLoadExecutor-1857-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2af96594[collection1] main]
   [junit4]   2> 505981 INFO  (coreLoadExecutor-1857-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\collection1\conf
   [junit4]   2> 505996 INFO  (coreLoadExecutor-1857-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 505996 INFO  (coreLoadExecutor-1857-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 505996 INFO  (searcherExecutor-1858-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2af96594[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 506371 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-002\collection1
   [junit4]   2> 506387 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 506387 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@422b6ede{/solr,null,AVAILABLE}
   [junit4]   2> 506387 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@5640d4c3{HTTP/1.1,[http/1.1]}{127.0.0.1:61785}
   [junit4]   2> 506387 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.Server Started @511474ms
   [junit4]   2> 506387 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=61785}
   [junit4]   2> 506387 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 506387 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solrâ„¢ version 7.0.0
   [junit4]   2> 506387 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 506387 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 506387 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-11-29T20:41:35.530Z
   [junit4]   2> 506402 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-002\solr.xml
   [junit4]   2> 506402 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-002\.
   [junit4]   2> 506402 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 506434 INFO  (coreLoadExecutor-1867-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 506450 INFO  (coreLoadExecutor-1867-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 506450 INFO  (coreLoadExecutor-1867-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 506450 INFO  (coreLoadExecutor-1867-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-002\.\collection1
   [junit4]   2> 506450 INFO  (coreLoadExecutor-1867-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-002\.\collection1\data\]
   [junit4]   2> 506450 INFO  (coreLoadExecutor-1867-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=54.1279296875, floorSegmentMB=1.9638671875, forceMergeDeletesPctAllowed=1.5514710995267544, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7324973929379563
   [junit4]   2> 506465 INFO  (coreLoadExecutor-1867-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 506465 INFO  (coreLoadExecutor-1867-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 506465 INFO  (coreLoadExecutor-1867-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 506465 INFO  (coreLoadExecutor-1867-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@68426255[collection1] main]
   [junit4]   2> 506465 INFO  (coreLoadExecutor-1867-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-002\collection1\conf
   [junit4]   2> 506480 INFO  (coreLoadExecutor-1867-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
   [junit4]   2> 506480 INFO  (searcherExecutor-1868-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@68426255[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 506496 INFO  (qtp686928370-4340) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
   [junit4]   2> 506496 INFO  (indexFetcher-1872-thread-1) [    x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
   [junit4]   2> 506496 INFO  (indexFetcher-1872-thread-1) [    x:collection1] o.a.s.h.IndexFetcher Master's version: 0
   [junit4]   2> 506496 INFO  (indexFetcher-1872-thread-1) [    x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
   [junit4]   2> 506917 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@5640d4c3{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 506917 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=484381204
   [junit4]   2> 506917 INFO  (coreCloseExecutor-1873-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@307b7a36
   [junit4]   2> 506932 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@422b6ede{/solr,null,UNAVAILABLE}
   [junit4]   2> 506932 INFO  (qtp686928370-4335) [    x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 506932 INFO  (qtp686928370-4335) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 2
   [junit4]   2> 506932 INFO  (qtp686928370-4335) [    x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 506932 INFO  (qtp686928370-4335) [    x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@6898ff38
   [junit4]   2> 506947 INFO  (qtp686928370-4335) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@27621660[collection1] main]
   [junit4]   2> 506947 INFO  (searcherExecutor-1858-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@27621660[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 506947 INFO  (qtp686928370-4335) [    x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 506947 INFO  (qtp686928370-4335) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 7
   [junit4]   2> 506947 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@148fa274{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 506947 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1796706061
   [junit4]   2> 506947 INFO  (coreCloseExecutor-1875-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@52c6415d
   [junit4]   2> 506963 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7f8f1e2f{/solr,null,UNAVAILABLE}
   [junit4]   2> 506963 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 506963 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1fade1bc{/solr,null,AVAILABLE}
   [junit4]   2> 506963 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.AbstractConnector Started ServerConnector@6f8b186{HTTP/1.1,[http/1.1]}{127.0.0.1:61790}
   [junit4]   2> 506963 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.Server Started @512051ms
   [junit4]   2> 506963 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=61790}
   [junit4]   2> 506963 ERROR (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 506963 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solrâ„¢ version 7.0.0
   [junit4]   2> 506963 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 506963 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 506963 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-11-29T20:41:36.106Z
   [junit4]   2> 506963 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\solr.xml
   [junit4]   2> 506994 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\.
   [junit4]   2> 506994 INFO  (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 507026 INFO  (coreLoadExecutor-1882-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 507026 INFO  (coreLoadExecutor-1882-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 507041 INFO  (coreLoadExecutor-1882-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
   [junit4]   2> 507041 INFO  (coreLoadExecutor-1882-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\.\collection1
   [junit4]   2> 507041 INFO  (coreLoadExecutor-1882-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\.\collection1\data\]
   [junit4]   2> 507057 INFO  (coreLoadExecutor-1882-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=92.9423828125, floorSegmentMB=1.3818359375, forceMergeDeletesPctAllowed=18.01796487629434, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8144955782501662
   [junit4]   2> 507073 INFO  (coreLoadExecutor-1882-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 507073 INFO  (coreLoadExecutor-1882-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 507073 INFO  (coreLoadExecutor-1882-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.17121660533680683]
   [junit4]   2> 507073 INFO  (coreLoadExecutor-1882-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@34360b82[collection1] main]
   [junit4]   2> 507073 INFO  (coreLoadExecutor-1882-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001\solr-instance-001\collection1\conf
   [junit4]   2> 507073 INFO  (coreLoadExecutor-1882-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
   [junit4]   2> 507073 INFO  (coreLoadExecutor-1882-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 507073 INFO  (searcherExecutor-1883-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@34360b82[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 507510 INFO  (qtp966621980-4379) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 4
   [junit4]   2> 507525 INFO  (qtp966621980-4382) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4382) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4382) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4382) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4382) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4382) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4382) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4382) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4382) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4382) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4383) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4384) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4385) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
   [junit4]   2> 507525 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 3
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
   [junit4]   2> 507541 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 4
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4386) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
   [junit4]   2> 507556 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 2
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
   [junit4]   2> 507572 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[87]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[88]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[89]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[90]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[91]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[93]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[94]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[95]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[96]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[97]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[98]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[99]} 0 0
   [junit4]   2> 507589 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[100]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[101]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[102]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[103]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[104]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[105]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[106]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[107]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[108]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[109]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[110]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[111]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[112]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[113]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[114]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[115]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[116]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[117]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[118]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[119]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[120]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[121]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[122]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[123]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[124]} 0 0
   [junit4]   2> 507604 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[125]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[126]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[127]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[128]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[129]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[130]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[131]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[132]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[133]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[134]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[135]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[136]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[137]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[138]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[139]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[140]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[141]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[142]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[143]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[144]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[145]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[146]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[147]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[148]} 0 0
   [junit4]   2> 507619 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[149]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[150]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[151]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[152]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[153]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[154]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[155]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[156]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[157]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[158]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[159]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[160]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[161]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[162]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[163]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[164]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[165]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[166]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[167]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[168]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[169]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[170]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[171]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[172]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[173]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[174]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[175]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[176]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[177]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[178]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[179]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[180]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[181]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[182]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[183]} 0 0
   [junit4]   2> 507635 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[184]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[185]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[186]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[187]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[188]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[189]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[190]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[191]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[192]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[193]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[194]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[195]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[196]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[197]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[198]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[199]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[200]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[201]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[202]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[203]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[204]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[205]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[206]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[207]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[208]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[209]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[210]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[211]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[212]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[213]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[214]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[215]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[216]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[217]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[218]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[219]} 0 0
   [junit4]   2> 507650 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[220]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[221]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[222]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[223]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[224]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[225]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[226]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[227]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[228]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[229]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[230]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[231]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[232]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[233]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[234]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[235]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[236]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[237]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[238]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[239]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[240]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[241]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[242]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[243]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[244]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[245]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[246]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[247]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[248]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[249]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[250]} 0 0
   [junit4]   2> 507666 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[251]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[252]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[253]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[254]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[255]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[256]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[257]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[258]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[259]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[260]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[261]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[262]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[263]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[264]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[265]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[266]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[267]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[268]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[269]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[270]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[271]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[272]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[273]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[274]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[275]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[276]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[277]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[278]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[279]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[280]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[281]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[282]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[283]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[284]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[285]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[286]} 0 0
   [junit4]   2> 507681 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[287]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[288]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[289]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[290]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[291]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[292]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[293]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[294]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[295]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[296]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[297]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[298]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[299]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[300]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[301]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[302]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[303]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[304]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[305]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[306]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[307]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[308]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[309]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[310]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[311]} 0 0
   [junit4]   2> 507698 INFO  (qtp966621980-4381) [    x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[312]} 0 0
 

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

a.s.h.IndexFetcher Slave's generation: 4
   [junit4]   2> 607853 INFO  (indexFetcher-2493-thread-1) [    x:collection1] o.a.s.h.IndexFetcher Slave in sync with master.
   [junit4]   2> 608044 WARN  (qtp1381178261-5470) [    x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master 
   [junit4]   2> org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:62324/solr: Expected mime type application/octet-stream but got text/html. <html>
   [junit4]   2> <head>
   [junit4]   2> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
   [junit4]   2> <title>Error 404 </title>
   [junit4]   2> </head>
   [junit4]   2> <body>
   [junit4]   2> <h2>HTTP ERROR: 404</h2>
   [junit4]   2> <p>Problem accessing /solr/replication. Reason:
   [junit4]   2> <pre>    Can not find: /solr/replication</pre></p>
   [junit4]   2> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.14.v20161028</a><hr/>
   [junit4]   2> </body>
   [junit4]   2> </html>
   [junit4]   2> 
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:578)
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:262)
   [junit4]   2> 	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251)
   [junit4]   2> 	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
   [junit4]   2> 	at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1726)
   [junit4]   2> 	at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:938)
   [junit4]   2> 	at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:312)
   [junit4]   2> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:152)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:2228)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:658)
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:464)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:303)
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:254)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:113)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
   [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]   2> 	at org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]   2> 	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]   2> 	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]   2> 	at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.execute(ExecuteProduceConsume.java:100)
   [junit4]   2> 	at org.eclipse.jetty.io.ManagedSelector.run(ManagedSelector.java:147)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 608044 INFO  (qtp1381178261-5470) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=6
   [junit4]   2> 608046 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@4a7e1546{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 608047 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1668735444
   [junit4]   2> 608047 INFO  (coreCloseExecutor-2507-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@46a594bb
   [junit4]   2> 608049 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6b9cbf3d{/solr,null,UNAVAILABLE}
   [junit4]   2> 608050 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[7FC99266A7F8801B]) [    ] o.a.s.SolrTestCaseJ4 ###Ending doTestDetails
   [junit4]   2> 608051 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@41b91670{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 608051 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=161848032
   [junit4]   2> 608054 INFO  (coreCloseExecutor-2509-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@7fbd6f1b
   [junit4]   2> 608056 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5650b009{/solr,null,UNAVAILABLE}
   [junit4]   2> 608059 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@59f8443{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 608059 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[7FC99266A7F8801B]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1531675782
   [junit4]   2> 608060 INFO  (coreCloseExecutor-2511-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@25fc2b6a
   [junit4]   2> 608063 INFO  (TEST-TestReplicationHandler.doTestDetails-seed#[7FC99266A7F8801B]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4861724c{/solr,null,UNAVAILABLE}
   [junit4]   2> 608066 INFO  (SUITE-TestReplicationHandler-seed#[7FC99266A7F8801B]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_7FC99266A7F8801B-001
   [junit4]   2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=true): {}, locale=es-NI, timezone=America/St_Kitts
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=96112240,total=196726784
   [junit4]   2> NOTE: All tests run in this JVM: [TestPartialUpdateDeduplication, DisMaxRequestHandlerTest, TestLockTree, AddBlockUpdateTest, QueryElevationComponentTest, TestNumericTerms64, CloudMLTQParserTest, TestNRTOpen, RulesTest, VersionInfoTest, TestExtendedDismaxParser, TriLevelCompositeIdRoutingTest, PeerSyncTest, TestManagedSchemaThreadSafety, TestCloudDeleteByQuery, TestTolerantSearch, OverseerTest, NumericFieldsTest, SSLMigrationTest, OverseerCollectionConfigSetProcessorTest, CollectionsAPIAsyncDistributedZkTest, TestElisionMultitermQuery, TestXmlQParserPlugin, CloudExitableDirectoryReaderTest, LeaderFailoverAfterPartitionTest, DistributedFacetPivotSmallTest, TestDocumentBuilder, ReplicaListTransformerTest, RecoveryZkTest, DateFieldTest, TestFastLRUCache, PKIAuthenticationIntegrationTest, DocumentAnalysisRequestHandlerTest, ActionThrottleTest, HdfsChaosMonkeyNothingIsSafeTest, PolyFieldTest, IndexBasedSpellCheckerTest, TestCollationField, TestDistribDocBasedVersion, TestReRankQParserPlugin, ShardRoutingTest, TestExportWriter, InfoHandlerTest, AssignTest, DirectUpdateHandlerTest, TestPKIAuthenticationPlugin, ExplicitHLLTest, TestHighFrequencyDictionaryFactory, PeerSyncWithIndexFingerprintCachingTest, QueryResultKeyTest, OutputWriterTest, TestSolr4Spatial2, TermsComponentTest, TestFileDictionaryLookup, TimeZoneUtilsTest, SolrRequestParserTest, TestClassicSimilarityFactory, TestRestoreCore, TestRequestStatusCollectionAPI, AlternateDirectoryTest, TestSolrDynamicMBean, TestCustomSort, SpatialFilterTest, PingRequestHandlerTest, SuggestComponentTest, SimpleFacetsTest, DocValuesMultiTest, TestReplicationHandler]
   [junit4] Completed [166/658 (1!)] on J0 in 102.33s, 15 tests, 1 failure, 1 skipped <<< FAILURES!

[...truncated 64035 lines...]