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 2017/01/29 06:36:05 UTC
[JENKINS] Lucene-Solr-6.x-Windows (64bit/jdk1.8.0_121) - Build #
708 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/708/
Java: 64bit/jdk1.8.0_121 -XX:-UseCompressedOops -XX:+UseSerialGC
1 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([A21233472B51214:629E161EA22F00F8]: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:141)
at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:295)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
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:368)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 12507 lines...]
[junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWithDelegationTokens_A21233472B51214-001\init-core-data-001
[junit4] 2> 2350323 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A21233472B51214]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 2350335 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A21233472B51214]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWithDelegationTokens_A21233472B51214-001\tempDir-001
[junit4] 2> 2350336 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A21233472B51214]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2350336 INFO (Thread-5184) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2350336 INFO (Thread-5184) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2350436 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A21233472B51214]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:49687
[junit4] 2> 2350446 INFO (jetty-launcher-3174-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2350446 INFO (jetty-launcher-3174-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2350446 INFO (jetty-launcher-3174-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@43b744ec{/solr,null,AVAILABLE}
[junit4] 2> 2350447 INFO (jetty-launcher-3174-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4c87476f{/solr,null,AVAILABLE}
[junit4] 2> 2350447 INFO (jetty-launcher-3174-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4b5bac79{HTTP/1.1,[http/1.1]}{127.0.0.1:49691}
[junit4] 2> 2350447 INFO (jetty-launcher-3174-thread-1) [ ] o.e.j.s.Server Started @2358348ms
[junit4] 2> 2350447 INFO (jetty-launcher-3174-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49691}
[junit4] 2> 2350447 ERROR (jetty-launcher-3174-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2350448 INFO (jetty-launcher-3174-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.5.0
[junit4] 2> 2350447 INFO (jetty-launcher-3174-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@53c9454d{HTTP/1.1,[http/1.1]}{127.0.0.1:49694}
[junit4] 2> 2350448 INFO (jetty-launcher-3174-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2350448 INFO (jetty-launcher-3174-thread-2) [ ] o.e.j.s.Server Started @2358348ms
[junit4] 2> 2350448 INFO (jetty-launcher-3174-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2350448 INFO (jetty-launcher-3174-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=49694}
[junit4] 2> 2350448 INFO (jetty-launcher-3174-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-29T05:58:15.581Z
[junit4] 2> 2350448 ERROR (jetty-launcher-3174-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2350448 INFO (jetty-launcher-3174-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 6.5.0
[junit4] 2> 2350448 INFO (jetty-launcher-3174-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2350448 INFO (jetty-launcher-3174-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2350448 INFO (jetty-launcher-3174-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-29T05:58:15.581Z
[junit4] 2> 2350456 INFO (jetty-launcher-3174-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2350456 INFO (jetty-launcher-3174-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2350462 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 0x159e8cd72b30002, 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> 2350466 INFO (jetty-launcher-3174-thread-2) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2350467 INFO (jetty-launcher-3174-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49687/solr
[junit4] 2> 2350473 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 0x159e8cd72b30003, 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> 2350477 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 0x159e8cd72b30001, 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> 2350479 INFO (jetty-launcher-3174-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2350480 INFO (jetty-launcher-3174-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49687/solr
[junit4] 2> 2350507 INFO (jetty-launcher-3174-thread-2) [n:127.0.0.1:49694_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49694_solr
[junit4] 2> 2350508 INFO (jetty-launcher-3174-thread-2) [n:127.0.0.1:49694_solr ] o.a.s.c.Overseer Overseer (id=97364836055449604-127.0.0.1:49694_solr-n_0000000000) starting
[junit4] 2> 2350510 INFO (jetty-launcher-3174-thread-1) [n:127.0.0.1:49691_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49691_solr
[junit4] 2> 2350513 INFO (zkCallback-3181-thread-1-processing-n:127.0.0.1:49694_solr) [n:127.0.0.1:49694_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2350513 INFO (zkCallback-3185-thread-1-processing-n:127.0.0.1:49691_solr) [n:127.0.0.1:49691_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2350520 INFO (jetty-launcher-3174-thread-2) [n:127.0.0.1:49694_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49694_solr
[junit4] 2> 2350525 INFO (zkCallback-3185-thread-1-processing-n:127.0.0.1:49691_solr) [n:127.0.0.1:49691_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2350525 INFO (zkCallback-3181-thread-1-processing-n:127.0.0.1:49694_solr) [n:127.0.0.1:49694_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2350543 INFO (jetty-launcher-3174-thread-2) [n:127.0.0.1:49694_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 2350562 INFO (jetty-launcher-3174-thread-1) [n:127.0.0.1:49691_solr ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 2350611 INFO (jetty-launcher-3174-thread-2) [n:127.0.0.1:49694_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> 2350611 INFO (jetty-launcher-3174-thread-1) [n:127.0.0.1:49691_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> 2350971 INFO (jetty-launcher-3174-thread-1) [n:127.0.0.1:49691_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 2350971 INFO (jetty-launcher-3174-thread-2) [n:127.0.0.1:49694_solr ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 2351078 INFO (jetty-launcher-3174-thread-1-EventThread) [n:127.0.0.1:49691_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 2351078 INFO (jetty-launcher-3174-thread-2-EventThread) [n:127.0.0.1:49694_solr ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 2351312 ERROR (jetty-launcher-3174-thread-2) [n:127.0.0.1:49694_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:185)
[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:142)
[junit4] 2> at org.apache.solr.core.CoreContainer.initializeAuthenticationPlugin(CoreContainer.java:339)
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:484)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:235)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:167)
[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:385)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:248)
[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> 2351581 INFO (jetty-launcher-3174-thread-2) [n:127.0.0.1:49694_solr ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring the default client with: org.apache.solr.security.HttpParamDelegationTokenPlugin$2@135c2881
[junit4] 2> 2351581 INFO (jetty-launcher-3174-thread-1) [n:127.0.0.1:49691_solr ] o.a.s.h.c.HttpShardHandlerFactory Reconfiguring the default client with: org.apache.solr.security.HttpParamDelegationTokenPlugin$2@73f4303f
[junit4] 2> 2351581 INFO (jetty-launcher-3174-thread-1) [n:127.0.0.1:49691_solr ] o.a.s.u.UpdateShardHandler Reconfiguring the default client with: org.apache.solr.security.HttpParamDelegationTokenPlugin$2@73f4303f
[junit4] 2> 2351581 INFO (jetty-launcher-3174-thread-2) [n:127.0.0.1:49694_solr ] o.a.s.u.UpdateShardHandler Reconfiguring the default client with: org.apache.solr.security.HttpParamDelegationTokenPlugin$2@135c2881
[junit4] 2> 2351584 INFO (jetty-launcher-3174-thread-1) [n:127.0.0.1:49691_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWithDelegationTokens_A21233472B51214-001\tempDir-001\node2\.
[junit4] 2> 2351586 INFO (jetty-launcher-3174-thread-2) [n:127.0.0.1:49694_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWithDelegationTokens_A21233472B51214-001\tempDir-001\node1\.
[junit4] 2> 2352763 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel
[junit4] 2> 2354258 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354261 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354265 ERROR (qtp1445327090-19508) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_1]
[junit4] 2> 2354298 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 0x159e8cd72b3000a, 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> 2354320 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354321 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354335 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354337 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 0x159e8cd72b3000b, 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> 2354344 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354347 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354349 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354351 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 0x159e8cd72b3000c, 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> 2354394 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354395 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354398 ERROR (qtp1445327090-19502) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2]
[junit4] 2> 2354402 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 0x159e8cd72b3000d, 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> 2354406 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354435 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354436 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354440 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354441 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 0x159e8cd72b3000e, 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> 2354442 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel
[junit4] 2> 2354466 INFO (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths
[junit4] 2> 2354503 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 0x159e8cd72b3000f, 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> 2354503 INFO (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths
[junit4] 2> 2354527 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient
[junit4] 2> 2354537 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354555 INFO (qtp1445327090-19520) [n:127.0.0.1:49694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYMNigFaDNoHDQQDFA2XFu5GJTqQXckBOD3ntN-aHJ-mB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2354555 INFO (qtp1445327090-19520) [n:127.0.0.1:49694_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYMNigFaDNoHDQQDFA2XFu5GJTqQXckBOD3ntN-aHJ-mB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2354556 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient
[junit4] 2> 2354577 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify
[junit4] 2> 2354586 INFO (qtp1445327090-19506) [n:127.0.0.1:49694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYM_igFaDNoHPwUDFJ1IKhDZONQNVuga8Hu8WwV3W0bkB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2354586 INFO (qtp1445327090-19506) [n:127.0.0.1:49694_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYM_igFaDNoHPwUDFJ1IKhDZONQNVuga8Hu8WwV3W0bkB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2354599 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354600 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354602 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354604 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 0x159e8cd72b30010, 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> 2354609 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354610 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354612 INFO (qtp1445327090-19508) [n:127.0.0.1:49694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYM_igFaDNoHPwUDFJ1IKhDZONQNVuga8Hu8WwV3W0bkB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2354612 INFO (qtp1445327090-19508) [n:127.0.0.1:49694_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYM_igFaDNoHPwUDFJ1IKhDZONQNVuga8Hu8WwV3W0bkB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2354613 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 0x159e8cd72b30011, 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> 2354617 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354617 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify
[junit4] 2> 2354640 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail
[junit4] 2> 2354651 WARN (qtp1445327090-19519) [ ] 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> 2354652 WARN (qtp1445327090-19519) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 2354652 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354655 WARN (qtp1362293273-19495) [ ] 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> 2354656 WARN (qtp1362293273-19495) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 2354656 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354663 WARN (qtp1445327090-19507) [ ] 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> 2354664 WARN (qtp1445327090-19507) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 2354664 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354666 WARN (qtp1362293273-19497) [ ] 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> 2354666 WARN (qtp1362293273-19497) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.authentication.requestcontinues
[junit4] 2> 2354666 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354667 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail
[junit4] 2> 2354685 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew
[junit4] 2> 2354714 INFO (qtp1445327090-19521) [n:127.0.0.1:49694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYOrigFaDNoHqwgDFI_b6CR5EECRvec6dpwTkAh9YutFB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2354714 INFO (qtp1445327090-19521) [n:127.0.0.1:49694_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYOrigFaDNoHqwgDFI_b6CR5EECRvec6dpwTkAh9YutFB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2354741 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354742 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354745 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354745 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 0x159e8cd72b30012, 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> 2354750 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354752 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354754 INFO (qtp1445327090-19502) [n:127.0.0.1:49694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYOrigFaDNoHqwgDFI_b6CR5EECRvec6dpwTkAh9YutFB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2354754 INFO (qtp1445327090-19502) [n:127.0.0.1:49694_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYOrigFaDNoHqwgDFI_b6CR5EECRvec6dpwTkAh9YutFB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2354755 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 0x159e8cd72b30013, 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> 2354759 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354760 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354763 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354781 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354783 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354784 INFO (qtp1445327090-19509) [n:127.0.0.1:49694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYP6igFaDNoH-gkDFJG2be-axJjHMAbQNCXdg5h0TVpGB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2354785 INFO (qtp1445327090-19509) [n:127.0.0.1:49694_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYP6igFaDNoH-gkDFJG2be-axJjHMAbQNCXdg5h0TVpGB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2354785 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 0x159e8cd72b30015, 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> 2354791 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354793 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354795 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354796 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 0x159e8cd72b30016, 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> 2354798 INFO (qtp1362293273-19516) [n:127.0.0.1:49691_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYP6igFaDNoH-gkDFJG2be-axJjHMAbQNCXdg5h0TVpGB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2354799 INFO (qtp1362293273-19516) [n:127.0.0.1:49691_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYP6igFaDNoH-gkDFJG2be-axJjHMAbQNCXdg5h0TVpGB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2354804 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354804 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354806 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354808 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 0x159e8cd72b30017, 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> 2354826 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354827 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354829 INFO (qtp1445327090-19509) [n:127.0.0.1:49694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYQnigFaDNoIJwoDFE30z3rqNskbqAHcSbkNUzXJZQHQB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2354829 INFO (qtp1445327090-19509) [n:127.0.0.1:49694_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYQnigFaDNoIJwoDFE30z3rqNskbqAHcSbkNUzXJZQHQB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2354829 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 0x159e8cd72b30018, 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> 2354833 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354848 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354849 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354851 INFO (qtp1445327090-19520) [n:127.0.0.1:49694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYQnigFaDNoIJwoDFE30z3rqNskbqAHcSbkNUzXJZQHQB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2354851 INFO (qtp1445327090-19520) [n:127.0.0.1:49694_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYQnigFaDNoIJwoDFE30z3rqNskbqAHcSbkNUzXJZQHQB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2354853 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 0x159e8cd72b30019, 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> 2354880 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354882 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354884 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354885 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 0x159e8cd72b3001a, 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> 2354905 INFO (qtp1445327090-19508) [n:127.0.0.1:49694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYR1igFaDNoIdQsDFBoOziCf8FUzpLQBqBJFcDzwqyLYB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2354905 INFO (qtp1445327090-19508) [n:127.0.0.1:49694_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYR1igFaDNoIdQsDFBoOziCf8FUzpLQBqBJFcDzwqyLYB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2354908 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354919 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2354920 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49687/solr ready
[junit4] 2> 2354923 INFO (qtp1445327090-19502) [n:127.0.0.1:49694_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYR1igFaDNoIdQsDFBoOziCf8FUzpLQBqBJFcDzwqyLYB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2354923 INFO (qtp1445327090-19502) [n:127.0.0.1:49694_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVnozYR1igFaDNoIdQsDFBoOziCf8FUzpLQBqBJFcDzwqyLYB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2354924 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 0x159e8cd72b3001b, 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> 2354927 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires= , 01- -1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: , 01- -1970 00:00:00 GMT
[junit4] 2> 2354928 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew
[junit4] 2> 2354948 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp
[junit4] 2> 2354951 INFO (qtp1445327090-19507) [n:127.0.0.1:49694_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> 2354951 INFO (qtp1445327090-19507) [n:127.0.0.1:49694_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> 2354951 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[A21233472B51214]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=bar&p=bar&t=test&e=1485705500084&s=DTIrqT07+iU1m21KS/jqpwl2kkw="; Path=/; Domain=127.0.0.1; Expires= , 29- -2017 15:58:20 GMT; HttpOnly". Invalid 'expires' attribute: , 29- -2017 15:58:20 GMT
[junit4] 2> 2354951 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp
[junit4] 2> 2354975 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
[junit4] 2> 2354993 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[A21233472B51214]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCloudWithDelegationTokens -Dtests.method=testDelegationTokenCancelFail -Dtests.seed=A21233472B51214 -Dtests.slow=true -Dtests.locale=ar-SY -Dtests.timezone=Africa/Bamako -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 0.04s J1 | TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<200> but was:<404>
[junit4] > at __randomizedtesting.SeedInfo.seed([A21233472B51214:629E161EA22F00F8]:0)
[junit4] > at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.cancelDelegationToken(TestSolrCloudWithDelegationTokens.java:141)
[junit4] > at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail(TestSolrCloudWithDelegationTokens.java:295)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2354998 INFO (jetty-launcher-3174-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@53c9454d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2354998 INFO (jetty-launcher-3174-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4b5bac79{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2354998 INFO (jetty-launcher-3174-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1854294381
[junit4] 2> 2355003 INFO (jetty-launcher-3174-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=793127993
[junit4] 2> 2355009 INFO (jetty-launcher-3174-thread-1) [ ] o.a.s.c.Overseer Overseer (id=97364836055449604-127.0.0.1:49694_solr-n_0000000000) closing
[junit4] 2> 2355013 INFO (OverseerStateUpdate-97364836055449604-127.0.0.1:49694_solr-n_0000000000) [n:127.0.0.1:49694_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:49694_solr
[junit4] 2> 2355013 WARN (zkCallback-3185-thread-1-processing-n:127.0.0.1:49691_solr) [n:127.0.0.1:49691_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2355014 INFO (jetty-launcher-3174-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 2355014 ERROR (Thread[Thread-5208,5,TGRP-TestSolrCloudWithDelegationTokens]) [n:127.0.0.1:49691_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 2355018 WARN (zkCallback-3181-thread-3-processing-n:127.0.0.1:49694_solr) [n:127.0.0.1:49694_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2355018 INFO (jetty-launcher-3174-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 2355018 ERROR (Thread[Thread-5207,5,TGRP-TestSolrCloudWithDelegationTokens]) [n:127.0.0.1:49694_solr ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 2355094 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 0x159e8cd72b30007, 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> 2355094 INFO (jetty-launcher-3174-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@43b744ec{/solr,null,UNAVAILABLE}
[junit4] 2> 2355096 INFO (jetty-launcher-3174-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4c87476f{/solr,null,UNAVAILABLE}
[junit4] 2> 2355097 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 0x159e8cd72b30008, 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> 2355100 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A21233472B51214]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:49687 49687
[junit4] 2> 2355103 INFO (Thread-5184) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:49687 49687
[junit4] 2> 2355104 WARN (Thread-5184) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/aliases.json
[junit4] 2> 19 /solr/clusterprops.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 19 /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> 19 /solr/live_nodes
[junit4] 2> 19 /solr/collections
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 3 /solr/overseer/queue
[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> 2355105 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[A21233472B51214]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestSolrCloudWithDelegationTokens_A21233472B51214-001
[junit4] 2> Jan 29, 2017 5:58:20 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=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=697, maxMBSortInHeap=7.626694363906464, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=ar-SY, timezone=Africa/Bamako
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=3,threads=1,free=253391368,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [ScriptEngineTest, ShardRoutingCustomTest, TlogReplayBufferedWhileIndexingTest, SolrGraphiteReporterTest, SuggestComponentTest, DirectoryFactoryTest, TestSegmentSorting, SpellingQueryConverterTest, OutOfBoxZkACLAndCredentialsProvidersTest, BasicZkTest, TestUtils, PreAnalyzedFieldManagedSchemaCloudTest, TestDistributedGrouping, TestBulkSchemaConcurrent, SparseHLLTest, TestReplicaProperties, TestCustomStream, TestConfigSets, TestRawTransformer, ReturnFieldsTest, TestConfigSetsAPIExclusivity, ReplicaListTransformerTest, HdfsChaosMonkeySafeLeaderTest, CurrencyFieldOpenExchangeTest, CoreAdminHandlerTest, EchoParamsTest, TestFuzzyAnalyzedSuggestions, FieldAnalysisRequestHandlerTest, SpatialHeatmapFacetsTest, CdcrBootstrapTest, CursorMarkTest, ExplicitHLLTest, LukeRequestHandlerTest, TestBinaryResponseWriter, TestXmlQParser, SSLMigrationTest, TestRuleBasedAuthorizationPlugin, TestAuthorizationFramework, TestComplexPhraseQParserPlugin, RegexBoostProcessorTest, MBeansHandlerTest, BlockJoinFacetDistribTest, BasicFunctionalityTest, DistributedFacetExistsSmallTest, SortByFunctionTest, TestJsonRequest, TestQueryTypes, TestMissingGroups, TestSchemaManager, DirectUpdateHandlerOptimizeTest, LeaderInitiatedRecoveryOnShardRestartTest, TestDistribDocBasedVersion, AsyncCallRequestStatusResponseTest, FieldMutatingUpdateProcessorTest, FullSolrCloudDistribCmdsTest, SharedFSAutoReplicaFailoverUtilsTest, TestSolrDeletionPolicy2, HdfsSyncSliceTest, TestExactStatsCache, TestPerFieldSimilarityClassic, SuggesterWFSTTest, JvmMetricsTest, TestQueryUtils, LeaderFailureAfterFreshStartTest, TestRealTimeGet, CacheHeaderTest, DistribCursorPagingTest, TestNamedUpdateProcessors, TestSimpleQParserPlugin, SegmentsInfoRequestHandlerTest, HdfsBasicDistributedZkTest, CdcrReplicationHandlerTest, HighlighterMaxOffsetTest, BasicDistributedZk2Test, QueryParsingTest, TestCloudPivotFacet, TestDocSet, TestDistributedMissingSort, StatsComponentTest, URLClassifyProcessorTest, TermVectorComponentTest, SolrSlf4jReporterTest, TestTrie, TestSolrFieldCacheMBean, TestSchemaResource, RecoveryAfterSoftCommitTest, TestRestManager, TestDynamicFieldResource, UUIDFieldTest, BinaryUpdateRequestHandlerTest, ZkStateWriterTest, TestIndexingPerformance, SolrCoreCheckLockOnStartupTest, TestExportWriter, TestUseDocValuesAsStored, TestImplicitCoreProperties, TestConfig, TestExclusionRuleCollectionAccess, NotRequiredUniqueKeyTest, DistributedTermsComponentTest, SpellCheckCollatorTest, TestSystemIdResolver, CollectionsAPIDistributedZkTest, TestFieldCacheSort, ShardSplitTest, CollectionTooManyReplicasTest, WordBreakSolrSpellCheckerTest, TestExpandComponent, TestStressCloudBlindAtomicUpdates, CheckHdfsIndexTest, TestOmitPositions, TestStressUserVersions, TestReload, MinimalSchemaTest, SearchHandlerTest, HighlighterConfigTest, TestLeaderInitiatedRecoveryThread, TestSearchPerf, TestManagedSchemaAPI, TestRandomFaceting, SolrIndexConfigTest, TestQuerySenderNoQuery, TestZkChroot, JSONWriterTest, TestSolrCLIRunExample, TestFieldResource, TestBackupRepositoryFactory, TestSolrConfigHandler, DeleteInactiveReplicaTest, SignatureUpdateProcessorFactoryTest, IndexSchemaTest, SuggesterTSTTest, DistributedSuggestComponentTest, TermVectorComponentDistributedTest, DistanceFunctionTest, PeerSyncWithIndexFingerprintCachingTest, TestDFISimilarityFactory, TestObjectReleaseTracker, CopyFieldTest, TestLMDirichletSimilarityFactory, TestSolr4Spatial2, OpenCloseCoreStressTest, TestIndexSearcher, CdcrUpdateLogTest, TestWriterPerf, JsonLoaderTest, TestPHPSerializedResponseWriter, TestGraphMLResponseWriter, RequestHandlersTest, TestCollapseQParserPlugin, TestCryptoKeys, TestInitParams, TestNumericTerms64, DistributedIntervalFacetingTest, TestSolrQueryParser, OverseerTaskQueueTest, DeleteNodeTest, TestEmbeddedSolrServerConstructors, LoggingHandlerTest, HLLSerializationTest, TestHdfsUpdateLog, TestLeaderElectionWithEmptyReplica, TestSlowCompositeReaderWrapper, TestReloadAndDeleteDocs, TestRandomDVFaceting, TestFaceting, TestRecovery, DistributedSpellCheckComponentTest, TestStressVersions, TestRangeQuery, SolrCoreTest, SolrCmdDistributorTest, DirectUpdateHandlerTest, HighlighterTest, ShowFileRequestHandlerTest, SolrIndexSplitterTest, TestExtendedDismaxParser, SolrRequestParserTest, SpatialFilterTest, NoCacheHeaderTest, TestWordDelimiterFilterFactory, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, TestSolrDeletionPolicy1, DebugComponentTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, TestReversedWildcardFilterFactory, PathHierarchyTokenizerFactoryTest, TestJmxIntegration, TestAnalyzedSuggestions, TestComponentsName, TestQuerySenderListener, AlternateDirectoryTest, BadComponentTest, TestMergePolicyConfig, TestElisionMultitermQuery, TestLuceneMatchVersion, SpellPossibilityIteratorTest, TestCodecSupport, TestIBSimilarityFactory, TimeZoneUtilsTest, ResourceLoaderTest, ChaosMonkeyNothingIsSafeTest, CleanupOldIndexTest, CollectionsAPISolrJTest, DeleteLastCustomShardedReplicaTest, DistribDocExpirationUpdateProcessorTest, DocValuesNotIndexedTest, ForceLeaderTest, LeaderElectionContextKeyTest, LeaderFailoverAfterPartitionTest, MissingSegmentRecoveryTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, RemoteQueryErrorTest, ReplaceNodeTest, RestartWhileUpdatingTest, RollingRestartTest, SimpleCollectionCreateDeleteTest, TestCloudDeleteByQuery, TestCloudInspectUtil, TestCloudRecovery, TestConfigSetsAPI, TestConfigSetsAPIZkFailure, TestHdfsCloudBackupRestore, TestLeaderElectionZkExpiry, TestLockTree, TestOnReconnectListenerSupport, TestRebalanceLeaders, TestSSLRandomization, TestSolrCloudWithDelegationTokens]
[junit4] Completed [513/681 (1!)] on J1 in 5.82s, 8 tests, 1 failure <<< FAILURES!
[...truncated 63013 lines...]
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_121) - Build #
709 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/709/
Java: 32bit/jdk1.8.0_121 -client -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIllegalFilePaths
Error Message:
Should have thrown exception on illegal path for param file and file name /foo/bar
Stack Trace:
java.lang.AssertionError: Should have thrown exception on illegal path for param file and file name /foo/bar
at __randomizedtesting.SeedInfo.seed([DEF3371C11C85E6F:2AEC1D6F000A38F6]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.doTestIllegalFilePaths(TestReplicationHandler.java:1438)
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:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
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:368)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11956 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\init-core-data-001
[junit4] 2> 1686569 INFO (SUITE-TestReplicationHandler-seed#[DEF3371C11C85E6F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1686571 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestIndexAndConfigAliasReplication
[junit4] 2> 1686572 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-001\collection1
[junit4] 2> 1686587 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1686589 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@37c86e{/solr,null,AVAILABLE}
[junit4] 2> 1686592 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@19140d7{HTTP/1.1,[http/1.1]}{127.0.0.1:60677}
[junit4] 2> 1686593 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.Server Started @1691230ms
[junit4] 2> 1686593 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=60677}
[junit4] 2> 1686593 ERROR (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1686593 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.5.0
[junit4] 2> 1686593 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1686593 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1686594 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-30T00:44:14.427Z
[junit4] 2> 1686595 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-001\solr.xml
[junit4] 2> 1686607 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1686617 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-001\.
[junit4] 2> 1686617 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1686654 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 1686674 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1686680 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1686689 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-001\.\collection1
[junit4] 2> 1686690 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 1686692 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=26, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20892166280553745]
[junit4] 2> 1686713 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1686713 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1686713 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.48869512530908343]
[junit4] 2> 1686714 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ccb65d[collection1] main]
[junit4] 2> 1686716 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-001\collection1\conf
[junit4] 2> 1686717 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1686717 INFO (coreLoadExecutor-6504-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1686717 INFO (searcherExecutor-6505-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ccb65d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1687458 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-002\collection1
[junit4] 2> 1687470 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1687471 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@16d5320{/solr,null,AVAILABLE}
[junit4] 2> 1687473 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2a45a4{HTTP/1.1,[http/1.1]}{127.0.0.1:60680}
[junit4] 2> 1687474 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.Server Started @1692111ms
[junit4] 2> 1687474 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=60680}
[junit4] 2> 1687474 ERROR (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1687475 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.5.0
[junit4] 2> 1687475 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1687475 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1687475 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-01-30T00:44:15.308Z
[junit4] 2> 1687476 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-002\solr.xml
[junit4] 2> 1687499 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1687508 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-002\.
[junit4] 2> 1687508 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigAliasReplication-seed#[DEF3371C11C85E6F]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1687529 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 1687544 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1687552 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1687558 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-002\.\collection1
[junit4] 2> 1687558 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 1687559 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=26, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20892166280553745]
[junit4] 2> 1687581 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1687581 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1687581 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.48869512530908343]
[junit4] 2> 1687582 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@16f7edb[collection1] main]
[junit4] 2> 1687582 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001\solr-instance-002\collection1\conf
[junit4] 2> 1687586 INFO (coreLoadExecutor-6514-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1687586 INFO (searcherExecutor-6515-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@16f7edb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1687593 INFO (qtp6451124-14586) [ 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> 1687596 INFO (indexFetcher-6519-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1687596 INFO (indexFetcher-6519-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1687596 INFO (indexFetcher-6519-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1688160 INFO (qtp6451124-14590) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 1688165 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 3
[junit4] 2> 1688168 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1688169 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1688170 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1688173 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1688174 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 1688175 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 1688180 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 1688182 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 1688185 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 1688187 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 1688189 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 1688192 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 1688193 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 1688196 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 1688197 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 1688200 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 1688201 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 1688204 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 1688205 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 1688207 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 1688209 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 1688211 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 1688212 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 1688213 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 1688215 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 1688218 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 1688221 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 1688222 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 1688224 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 1688225 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 1688227 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 1688229 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 1688230 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 1688233 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 1688234 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 1688237 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 1688238 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 1688239 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 1688240 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 1688242 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 1688243 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 1688245 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 1688247 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 1688248 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 1688249 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 1688251 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 1688252 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 1688254 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 1688257 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 1688258 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 1688259 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 1688262 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 1688264 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 1688267 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 1688268 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 1688270 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 1688272 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 1688273 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 1688274 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 1688275 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 1688277 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 1688278 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 1688280 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 1688282 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 1688284 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 1688285 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 1688287 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 1688289 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 1688291 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 1688293 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 1688295 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 1688297 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 1688298 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 1688300 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 1688301 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 1688303 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 1688305 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 1688307 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
[junit4] 2> 1688308 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0
[junit4] 2> 1688310 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
[junit4] 2> 1688312 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
[junit4] 2> 1688316 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
[junit4] 2> 1688317 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
[junit4] 2> 1688318 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 0
[junit4] 2> 1688319 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
[junit4] 2> 1688320 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
[junit4] 2> 1688323 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[87]} 0 0
[junit4] 2> 1688326 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[88]} 0 0
[junit4] 2> 1688329 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[89]} 0 0
[junit4] 2> 1688332 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[90]} 0 0
[junit4] 2> 1688334 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[91]} 0 0
[junit4] 2> 1688336 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 0
[junit4] 2> 1688338 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[93]} 0 0
[junit4] 2> 1688339 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[94]} 0 0
[junit4] 2> 1688341 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95]} 0 0
[junit4] 2> 1688344 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[96]} 0 0
[junit4] 2> 1688346 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[97]} 0 0
[junit4] 2> 1688347 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[98]} 0 0
[junit4] 2> 1688348 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[99]} 0 0
[junit4] 2> 1688350 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[100]} 0 0
[junit4] 2> 1688353 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[101]} 0 0
[junit4] 2> 1688356 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[102]} 0 0
[junit4] 2> 1688357 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[103]} 0 0
[junit4] 2> 1688358 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[104]} 0 0
[junit4] 2> 1688359 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[105]} 0 0
[junit4] 2> 1688360 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[106]} 0 0
[junit4] 2> 1688361 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[107]} 0 0
[junit4] 2> 1688363 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[108]} 0 0
[junit4] 2> 1688365 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[109]} 0 0
[junit4] 2> 1688366 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[110]} 0 0
[junit4] 2> 1688368 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[111]} 0 0
[junit4] 2> 1688369 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[112]} 0 0
[junit4] 2> 1688371 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[113]} 0 0
[junit4] 2> 1688372 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[114]} 0 0
[junit4] 2> 1688373 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[115]} 0 0
[junit4] 2> 1688374 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[116]} 0 0
[junit4] 2> 1688376 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[117]} 0 0
[junit4] 2> 1688378 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[118]} 0 0
[junit4] 2> 1688381 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[119]} 0 0
[junit4] 2> 1688383 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[120]} 0 0
[junit4] 2> 1688384 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[121]} 0 0
[junit4] 2> 1688386 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[122]} 0 0
[junit4] 2> 1688389 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[123]} 0 0
[junit4] 2> 1688391 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[124]} 0 0
[junit4] 2> 1688394 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[125]} 0 0
[junit4] 2> 1688396 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[126]} 0 0
[junit4] 2> 1688398 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[127]} 0 1
[junit4] 2> 1688399 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[128]} 0 0
[junit4] 2> 1688401 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[129]} 0 0
[junit4] 2> 1688403 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[130]} 0 0
[junit4] 2> 1688405 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[131]} 0 0
[junit4] 2> 1688406 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[132]} 0 0
[junit4] 2> 1688408 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[133]} 0 0
[junit4] 2> 1688410 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[134]} 0 0
[junit4] 2> 1688412 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[135]} 0 0
[junit4] 2> 1688413 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[136]} 0 0
[junit4] 2> 1688415 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[137]} 0 0
[junit4] 2> 1688416 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[138]} 0 0
[junit4] 2> 1688418 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[139]} 0 0
[junit4] 2> 1688419 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[140]} 0 0
[junit4] 2> 1688421 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[141]} 0 0
[junit4] 2> 1688423 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[142]} 0 0
[junit4] 2> 1688424 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[143]} 0 0
[junit4] 2> 1688426 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[144]} 0 0
[junit4] 2> 1688428 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[145]} 0 0
[junit4] 2> 1688429 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[146]} 0 0
[junit4] 2> 1688430 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[147]} 0 0
[junit4] 2> 1688431 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[148]} 0 0
[junit4] 2> 1688434 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[149]} 0 0
[junit4] 2> 1688435 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[150]} 0 0
[junit4] 2> 1688438 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[151]} 0 0
[junit4] 2> 1688439 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[152]} 0 0
[junit4] 2> 1688441 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[153]} 0 0
[junit4] 2> 1688443 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[154]} 0 0
[junit4] 2> 1688445 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[155]} 0 0
[junit4] 2> 1688447 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[156]} 0 0
[junit4] 2> 1688449 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[157]} 0 0
[junit4] 2> 1688450 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[158]} 0 0
[junit4] 2> 1688451 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[159]} 0 0
[junit4] 2> 1688452 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[160]} 0 0
[junit4] 2> 1688454 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[161]} 0 0
[junit4] 2> 1688455 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[162]} 0 0
[junit4] 2> 1688457 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[163]} 0 0
[junit4] 2> 1688458 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[164]} 0 0
[junit4] 2> 1688459 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[165]} 0 0
[junit4] 2> 1688461 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[166]} 0 0
[junit4] 2> 1688464 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[167]} 0 0
[junit4] 2> 1688466 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[168]} 0 0
[junit4] 2> 1688467 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[169]} 0 0
[junit4] 2> 1688469 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[170]} 0 0
[junit4] 2> 1688472 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[171]} 0 0
[junit4] 2> 1688473 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[172]} 0 0
[junit4] 2> 1688474 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[173]} 0 0
[junit4] 2> 1688475 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[174]} 0 0
[junit4] 2> 1688477 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[175]} 0 0
[junit4] 2> 1688478 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[176]} 0 0
[junit4] 2> 1688479 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[177]} 0 0
[junit4] 2> 1688480 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[178]} 0 0
[junit4] 2> 1688481 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[179]} 0 0
[junit4] 2> 1688482 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[180]} 0 0
[junit4] 2> 1688484 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[181]} 0 0
[junit4] 2> 1688486 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[182]} 0 0
[junit4] 2> 1688488 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[183]} 0 0
[junit4] 2> 1688490 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[184]} 0 0
[junit4] 2> 1688492 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[185]} 0 0
[junit4] 2> 1688494 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[186]} 0 0
[junit4] 2> 1688495 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[187]} 0 0
[junit4] 2> 1688498 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[188]} 0 0
[junit4] 2> 1688500 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[189]} 0 0
[junit4] 2> 1688502 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[190]} 0 0
[junit4] 2> 1688503 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[191]} 0 0
[junit4] 2> 1688505 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[192]} 0 0
[junit4] 2> 1688507 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[193]} 0 0
[junit4] 2> 1688509 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[194]} 0 0
[junit4] 2> 1688510 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[195]} 0 0
[junit4] 2> 1688511 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[196]} 0 0
[junit4] 2> 1688513 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[197]} 0 0
[junit4] 2> 1688514 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[198]} 0 0
[junit4] 2> 1688517 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[199]} 0 0
[junit4] 2> 1688518 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[200]} 0 0
[junit4] 2> 1688519 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[201]} 0 0
[junit4] 2> 1688521 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[202]} 0 0
[junit4] 2> 1688523 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[203]} 0 0
[junit4] 2> 1688524 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[204]} 0 0
[junit4] 2> 1688527 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[205]} 0 0
[junit4] 2> 1688528 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[206]} 0 0
[junit4] 2> 1688529 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[207]} 0 0
[junit4] 2> 1688530 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[208]} 0 0
[junit4] 2> 1688532 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[209]} 0 0
[junit4] 2> 1688533 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[210]} 0 0
[junit4] 2> 1688535 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[211]} 0 0
[junit4] 2> 1688537 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[212]} 0 0
[junit4] 2> 1688538 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[213]} 0 0
[junit4] 2> 1688539 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[214]} 0 0
[junit4] 2> 1688540 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[215]} 0 0
[junit4] 2> 1688542 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[216]} 0 0
[junit4] 2> 1688545 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[217]} 0 0
[junit4] 2> 1688547 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[218]} 0 0
[junit4] 2> 1688548 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[219]} 0 0
[junit4] 2> 1688551 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[220]} 0 0
[junit4] 2> 1688552 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[221]} 0 0
[junit4] 2> 1688554 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[222]} 0 0
[junit4] 2> 1688555 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[223]} 0 0
[junit4] 2> 1688558 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[224]} 0 0
[junit4] 2> 1688559 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[225]} 0 0
[junit4] 2> 1688560 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[226]} 0 0
[junit4] 2> 1688561 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[227]} 0 0
[junit4] 2> 1688563 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[228]} 0 0
[junit4] 2> 1688566 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[229]} 0 0
[junit4] 2> 1688568 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[230]} 0 0
[junit4] 2> 1688570 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[231]} 0 0
[junit4] 2> 1688573 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[232]} 0 0
[junit4] 2> 1688575 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[233]} 0 0
[junit4] 2> 1688576 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[234]} 0 0
[junit4] 2> 1688578 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[235]} 0 0
[junit4] 2> 1688579 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[236]} 0 0
[junit4] 2> 1688581 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[237]} 0 0
[junit4] 2> 1688584 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[238]} 0 0
[junit4] 2> 1688585 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[239]} 0 0
[junit4] 2> 1688586 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[240]} 0 0
[junit4] 2> 1688587 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[241]} 0 0
[junit4] 2> 1688589 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[242]} 0 0
[junit4] 2> 1688589 INFO (qtp6451124-14589) [ 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> 1688589 INFO (indexFetcher-6519-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1688589 INFO (indexFetcher-6519-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1688589 INFO (indexFetcher-6519-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1688590 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[243]} 0 0
[junit4] 2> 1688592 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[244]} 0 0
[junit4] 2> 1688594 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[245]} 0 0
[junit4] 2> 1688596 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[246]} 0 0
[junit4] 2> 1688597 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[247]} 0 0
[junit4] 2> 1688598 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[248]} 0 0
[junit4] 2> 1688600 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[249]} 0 0
[junit4] 2> 1688601 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[250]} 0 0
[junit4] 2> 1688604 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[251]} 0 0
[junit4] 2> 1688605 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[252]} 0 0
[junit4] 2> 1688607 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[253]} 0 0
[junit4] 2> 1688608 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[254]} 0 0
[junit4] 2> 1688610 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[255]} 0 0
[junit4] 2> 1688612 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[256]} 0 0
[junit4] 2> 1688614 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[257]} 0 0
[junit4] 2> 1688615 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[258]} 0 0
[junit4] 2> 1688617 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[259]} 0 0
[junit4] 2> 1688619 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[260]} 0 0
[junit4] 2> 1688621 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[261]} 0 0
[junit4] 2> 1688622 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[262]} 0 0
[junit4] 2> 1688624 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[263]} 0 0
[junit4] 2> 1688626 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[264]} 0 0
[junit4] 2> 1688628 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[265]} 0 0
[junit4] 2> 1688630 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[266]} 0 0
[junit4] 2> 1688633 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[267]} 0 0
[junit4] 2> 1688634 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[268]} 0 0
[junit4] 2> 1688635 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[269]} 0 0
[junit4] 2> 1688638 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[270]} 0 0
[junit4] 2> 1688640 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[271]} 0 0
[junit4] 2> 1688641 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[272]} 0 0
[junit4] 2> 1688642 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[273]} 0 0
[junit4] 2> 1688643 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[274]} 0 0
[junit4] 2> 1688644 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[275]} 0 0
[junit4] 2> 1688646 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[276]} 0 0
[junit4] 2> 1688647 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[277]} 0 0
[junit4] 2> 1688648 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[278]} 0 0
[junit4] 2> 1688650 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[279]} 0 0
[junit4] 2> 1688651 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[280]} 0 0
[junit4] 2> 1688653 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[281]} 0 0
[junit4] 2> 1688654 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[282]} 0 0
[junit4] 2> 1688655 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[283]} 0 0
[junit4] 2> 1688656 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[284]} 0 0
[junit4] 2> 1688657 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[285]} 0 0
[junit4] 2> 1688659 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[286]} 0 0
[junit4] 2> 1688661 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[287]} 0 0
[junit4] 2> 1688663 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[288]} 0 0
[junit4] 2> 1688665 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[289]} 0 0
[junit4] 2> 1688667 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[290]} 0 0
[junit4] 2> 1688669 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[291]} 0 0
[junit4] 2> 1688670 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[292]} 0 0
[junit4] 2> 1688673 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[293]} 0 0
[junit4] 2> 1688674 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[294]} 0 0
[junit4] 2> 1688675 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[295]} 0 0
[junit4] 2> 1688678 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[296]} 0 0
[junit4] 2> 1688679 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[297]} 0 0
[junit4] 2> 1688681 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[298]} 0 0
[junit4] 2> 1688682 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[299]} 0 0
[junit4] 2> 1688684 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[300]} 0 0
[junit4] 2> 1688686 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[301]} 0 0
[junit4] 2> 1688690 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[302]} 0 0
[junit4] 2> 1688692 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[303]} 0 0
[junit4] 2> 1688694 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[304]} 0 0
[junit4] 2> 1688696 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[305]} 0 0
[junit4] 2> 1688698 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[306]} 0 0
[junit4] 2> 1688700 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[307]} 0 0
[junit4] 2> 1688703 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[308]} 0 0
[junit4] 2> 1688706 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[309]} 0 0
[junit4] 2> 1688710 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[310]} 0 0
[junit4] 2> 1688711 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[311]} 0 0
[junit4] 2> 1688713 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[312]} 0 0
[junit4] 2> 1688716 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[313]} 0 0
[junit4] 2> 1688718 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[314]} 0 0
[junit4] 2> 1688720 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[315]} 0 0
[junit4] 2> 1688721 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[316]} 0 0
[junit4] 2> 1688724 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[317]} 0 0
[junit4] 2> 1688726 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[318]} 0 0
[junit4] 2> 1688727 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[319]} 0 0
[junit4] 2> 1688730 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[320]} 0 0
[junit4] 2> 1688731 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[321]} 0 0
[junit4] 2> 1688732 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[322]} 0 0
[junit4] 2> 1688733 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[323]} 0 0
[junit4] 2> 1688734 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[324]} 0 0
[junit4] 2> 1688737 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[325]} 0 0
[junit4] 2> 1688740 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[326]} 0 0
[junit4] 2> 1688743 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[327]} 0 0
[junit4] 2> 1688744 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[328]} 0 0
[junit4] 2> 1688745 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[329]} 0 0
[junit4] 2> 1688746 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[330]} 0 0
[junit4] 2> 1688747 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[331]} 0 0
[junit4] 2> 1688750 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[332]} 0 0
[junit4] 2> 1688751 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[333]} 0 0
[junit4] 2> 1688754 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[334]} 0 0
[junit4] 2> 1688756 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[335]} 0 0
[junit4] 2> 1688760 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[336]} 0 0
[junit4] 2> 1688761 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[337]} 0 0
[junit4] 2> 1688763 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[338]} 0 0
[junit4] 2> 1688765 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[339]} 0 0
[junit4] 2> 1688766 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[340]} 0 0
[junit4] 2> 1688768 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[341]} 0 0
[junit4] 2> 1688769 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[342]} 0 0
[junit4] 2> 1688770 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[343]} 0 0
[junit4] 2> 1688772 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[344]} 0 0
[junit4] 2> 1688773 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[345]} 0 0
[junit4] 2> 1688774 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[346]} 0 0
[junit4] 2> 1688775 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[347]} 0 0
[junit4] 2> 1688777 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[348]} 0 0
[junit4] 2> 1688778 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[349]} 0 0
[junit4] 2> 1688780 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[350]} 0 0
[junit4] 2> 1688781 INFO (qtp6451124-14593) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[351]} 0 0
[junit4] 2> 1688782 INFO (qtp6451124-14588) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[352]} 0 0
[junit4] 2> 1688784 INFO (qtp6451124-14586) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[353]} 0 0
[junit4] 2> 1688785 INFO (qtp6451124-14589) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[354]} 0 0
[junit4] 2> 1688787 INFO (qtp6451124-14590) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[355]} 0 0
[junit4] 2> 1688788 INFO (qtp6451124-14591) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[356]} 0 0
[junit4] 2> 1688790 INFO (qtp6451124-14592) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[357]} 0 0
[junit4] 2> 1688791 INFO (qtp645112
[...truncated too long message...]
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> 1813243 INFO (qtp7167942-15712) [ 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=12
[junit4] 2> 1813245 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@f52f08{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1813245 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=8719646
[junit4] 2> 1813248 INFO (coreCloseExecutor-7185-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@12533fb
[junit4] 2> 1813249 INFO (coreCloseExecutor-7185-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1813263 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1813263 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@b2df18{/solr,null,UNAVAILABLE}
[junit4] 2> 1813265 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestDetails
[junit4] 2> 1813266 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@a9ca02{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1813266 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=6355571
[junit4] 2> 1813268 INFO (coreCloseExecutor-7187-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1480415
[junit4] 2> 1813269 INFO (coreCloseExecutor-7187-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1813269 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1813270 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7a473e{/solr,null,UNAVAILABLE}
[junit4] 2> 1813273 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@55fcda{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1813273 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=15019416
[junit4] 2> 1813275 INFO (coreCloseExecutor-7189-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1793653
[junit4] 2> 1813278 INFO (coreCloseExecutor-7189-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 1813279 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1813279 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[DEF3371C11C85E6F]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@15891b{/solr,null,UNAVAILABLE}
[junit4] 2> 1813289 INFO (SUITE-TestReplicationHandler-seed#[DEF3371C11C85E6F]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandler_DEF3371C11C85E6F-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62), sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=zh-TW, timezone=Asia/Colombo
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_121 (32-bit)/cpus=3,threads=1,free=398758504,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [LoggingHandlerTest, TestUniqueKeyFieldResource, TestFastWriter, TestManagedResource, BasicFunctionalityTest, TestInitParams, BlockDirectoryTest, TestInitQParser, BlockJoinFacetSimpleTest, TestLockTree, TestUtils, ForceLeaderTest, UpdateParamsTest, TestSolrXml, CollectionReloadTest, DistributedQueryComponentCustomSortTest, TestSolrQueryParser, TestScoreJoinQPNoScore, JSONWriterTest, TestExactStatsCache, TestSolrDeletionPolicy2, TestReqParamsAPI, TestDynamicFieldResource, HdfsDirectoryFactoryTest, SuggestComponentTest, TestWriterPerf, TestManagedSchemaAPI, TestCollationFieldDocValues, UtilsToolTest, BasicZkTest, ConfigSetsAPITest, CopyFieldTest, ClusterStateTest, TestPerFieldSimilarityClassic, TestRandomFaceting, TestSearchPerf, HdfsNNFailoverTest, CdcrBootstrapTest, TestBackupRepositoryFactory, TestMacroExpander, TestSimpleQParserPlugin, ReturnFieldsTest, TestSolrQueryParserDefaultOperatorResource, QueryParsingTest, PreAnalyzedFieldManagedSchemaCloudTest, TestBulkSchemaConcurrent, TestMergePolicyConfig, TestXmlQParser, DateMathParserTest, ParsingFieldUpdateProcessorsTest, TestStressUserVersions, TestLMJelinekMercerSimilarityFactory, TestDocBasedVersionConstraints, VMParamsZkACLAndCredentialsProvidersTest, CollectionTooManyReplicasTest, TestSegmentSorting, ShardRoutingCustomTest, TestFieldCacheReopen, TestJsonFacetRefinement, TestSchemaResource, BigEndianAscendingWordSerializerTest, OverseerTaskQueueTest, TestOnReconnectListenerSupport, ResourceLoaderTest, TestCollectionAPI, RecoveryAfterSoftCommitTest, SolrSlf4jReporterTest, SpellCheckComponentTest, DistributedDebugComponentTest, TestEmbeddedSolrServerConstructors, TestRandomRequestDistribution, TestExportWriter, TestRecovery, CleanupOldIndexTest, AutoCommitTest, TestSolrConfigHandler, DeleteShardTest, TestUseDocValuesAsStored, TestSolrCloudWithHadoopAuthPlugin, SimpleMLTQParserTest, TestSolr4Spatial2, SortByFunctionTest, TestFieldResource, BadIndexSchemaTest, TestDefaultSearchFieldResource, TestHdfsUpdateLog, OutOfBoxZkACLAndCredentialsProvidersTest, ClassificationUpdateProcessorFactoryTest, RequestHandlersTest, TestAtomicUpdateErrorCases, TestSolrCloudWithKerberosAlt, TestSolrConfigHandlerCloud, TestDistributedGrouping, TestMissingGroups, TestCloudPivotFacet, TestCloudRecovery, TestDocSet, HdfsBasicDistributedZkTest, WordBreakSolrSpellCheckerTest, DistribCursorPagingTest, BasicAuthIntegrationTest, TestSolrFieldCacheMBean, SpellingQueryConverterTest, TestTrackingShardHandlerFactory, TestHighFrequencyDictionaryFactory, TestOmitPositions, SparseHLLTest, TestSchemaManager, TestQueryUtils, ResponseHeaderTest, NotRequiredUniqueKeyTest, SolrGraphiteReporterTest, TestCryptoKeys, SuggesterTSTTest, TestBinaryResponseWriter, RegexBoostProcessorTest, OpenCloseCoreStressTest, TestZkChroot, TestSuggestSpellingConverter, TestReload, TestOverriddenPrefixQueryForCustomFieldType, TestCrossCoreJoin, TestFiltering, TestIndexingPerformance, TestRandomCollapseQParserPlugin, JvmMetricsTest, RAMDirectoryFactoryTest, BasicDistributedZk2Test, TestClusterStateMutator, TestLeaderInitiatedRecoveryThread, SolrCoreCheckLockOnStartupTest, TestLuceneMatchVersion, HdfsRecoveryZkTest, EnumFieldTest, SolrIndexSplitterTest, ConnectionReuseTest, DistribJoinFromCollectionTest, TestConfig, CdcrUpdateLogTest, CurrencyFieldOpenExchangeTest, TestQueryTypes, FieldAnalysisRequestHandlerTest, TermVectorComponentDistributedTest, TestJoin, CdcrReplicationHandlerTest, TestCollapseQParserPlugin, SpatialHeatmapFacetsTest, DebugComponentTest, TestRealTimeGet, SSLMigrationTest, SolrXmlInZkTest, MetricsHandlerTest, SharedFSAutoReplicaFailoverUtilsTest, BlockJoinFacetDistribTest, CollectionsAPIDistributedZkTest, DefaultValueUpdateProcessorTest, TestSystemIdResolver, TestBadConfig, RestartWhileUpdatingTest, LeaderInitiatedRecoveryOnShardRestartTest, TestAnalyzeInfixSuggestions, ExternalFileFieldSortTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, RecoveryZkTest, TestReplicationHandler]
[junit4] Completed [354/681 (1!)] on J1 in 126.75s, 16 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 55780 lines...]