You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/10/02 23:53:16 UTC
[JENKINS-EA] Lucene-Solr-master-Linux (32bit/jdk-9-ea+136) - Build
# 17955 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17955/
Java: 32bit/jdk-9-ea+136 -client -XX:+UseSerialGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.schema.TestManagedSchemaThreadSafety
Error Message:
Clean up static fields (in @AfterClass?) and null them, your test still has references to classes of which the sizes cannot be measured due to security restrictions or Java 9 module encapsulation: - private static org.apache.solr.cloud.ZkTestServer org.apache.solr.schema.TestManagedSchemaThreadSafety.zkServer
Stack Trace:
junit.framework.AssertionFailedError: Clean up static fields (in @AfterClass?) and null them, your test still has references to classes of which the sizes cannot be measured due to security restrictions or Java 9 module encapsulation:
- private static org.apache.solr.cloud.ZkTestServer org.apache.solr.schema.TestManagedSchemaThreadSafety.zkServer
at __randomizedtesting.SeedInfo.seed([714F0B54C3F17EDF]:0)
at com.carrotsearch.randomizedtesting.rules.StaticFieldsInvariantRule$1.afterAlways(StaticFieldsInvariantRule.java:146)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
Caused by: java.lang.IllegalStateException: Unable to access 'private final sun.nio.ch.FileDispatcher sun.nio.ch.FileChannelImpl.nd' to estimate memory usage
at com.carrotsearch.randomizedtesting.rules.RamUsageEstimator.createCacheEntry(RamUsageEstimator.java:602)
at com.carrotsearch.randomizedtesting.rules.RamUsageEstimator.measureSizeOf(RamUsageEstimator.java:545)
at com.carrotsearch.randomizedtesting.rules.RamUsageEstimator.sizeOfAll(RamUsageEstimator.java:387)
at com.carrotsearch.randomizedtesting.rules.StaticFieldsInvariantRule$1.afterAlways(StaticFieldsInvariantRule.java:129)
... 10 more
Caused by: java.lang.reflect.InaccessibleObjectException: Unable to make member of class sun.nio.ch.FileChannelImpl accessible: module java.base does not export sun.nio.ch to unnamed module @5a3f96
at jdk.internal.reflect.Reflection.throwInaccessibleObjectException(java.base@9-ea/Reflection.java:414)
at java.lang.reflect.AccessibleObject.checkCanSetAccessible(java.base@9-ea/AccessibleObject.java:174)
at java.lang.reflect.Field.checkCanSetAccessible(java.base@9-ea/Field.java:171)
at java.lang.reflect.Field.setAccessible(java.base@9-ea/Field.java:165)
at com.carrotsearch.randomizedtesting.rules.RamUsageEstimator$3.run(RamUsageEstimator.java:597)
at com.carrotsearch.randomizedtesting.rules.RamUsageEstimator$3.run(RamUsageEstimator.java:594)
at java.security.AccessController.doPrivileged(java.base@9-ea/Native Method)
at com.carrotsearch.randomizedtesting.rules.RamUsageEstimator.createCacheEntry(RamUsageEstimator.java:594)
... 13 more
Build Log:
[...truncated 10628 lines...]
[junit4] Suite: org.apache.solr.schema.TestManagedSchemaThreadSafety
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.schema.TestManagedSchemaThreadSafety_714F0B54C3F17EDF-001/init-core-data-001
[junit4] 2> 127426 INFO (SUITE-TestManagedSchemaThreadSafety-seed#[714F0B54C3F17EDF]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 127426 INFO (SUITE-TestManagedSchemaThreadSafety-seed#[714F0B54C3F17EDF]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 127426 INFO (Thread-207) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 127426 INFO (Thread-207) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 127526 INFO (SUITE-TestManagedSchemaThreadSafety-seed#[714F0B54C3F17EDF]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:42912
[junit4] 2> 127528 INFO (TEST-TestManagedSchemaThreadSafety.testThreadSafety-seed#[714F0B54C3F17EDF]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testThreadSafety
[junit4] 2> 127529 DEBUG (TEST-TestManagedSchemaThreadSafety.testThreadSafety-seed#[714F0B54C3F17EDF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 127531 DEBUG (TEST-TestManagedSchemaThreadSafety.testThreadSafety-seed#[714F0B54C3F17EDF]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 127532 DEBUG (TEST-TestManagedSchemaThreadSafety.testThreadSafety-seed#[714F0B54C3F17EDF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 127533 DEBUG (TEST-TestManagedSchemaThreadSafety.testThreadSafety-seed#[714F0B54C3F17EDF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/managed-config/solrconfig.xml
[junit4] 2> 127535 DEBUG (TEST-TestManagedSchemaThreadSafety.testThreadSafety-seed#[714F0B54C3F17EDF]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/managed-config/schema.xml
[junit4] 2> 127536 DEBUG (TEST-TestManagedSchemaThreadSafety.testThreadSafety-seed#[714F0B54C3F17EDF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 127536 DEBUG (TEST-TestManagedSchemaThreadSafety.testThreadSafety-seed#[714F0B54C3F17EDF]-EventThread) [ ] o.a.s.c.c.SolrZkClient Submitting job to respond to event WatchedEvent state:SyncConnected type:None path:null
[junit4] 2> 127537 DEBUG (TEST-TestManagedSchemaThreadSafety.testThreadSafety-seed#[714F0B54C3F17EDF]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 127807 INFO (threadpool-153-thread-2) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 127815 INFO (threadpool-153-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 127818 INFO (threadpool-153-thread-1) [ ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 127823 INFO (threadpool-153-thread-1) [ ] o.a.s.s.IndexSchema [null] Schema name=minimal
[junit4] 2> 127825 INFO (threadpool-153-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 127832 INFO (threadpool-153-thread-1) [ ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/managed-config/managed-schema
[junit4] 2> 127833 DEBUG (threadpool-153-thread-1) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/managed-config/schema.xml.bak
[junit4] 2> 127834 INFO (threadpool-153-thread-1) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/managed-config/schema.xml to /configs/managed-config/schema.xml.bak
[junit4] 2> 128318 INFO (threadpool-153-thread-2) [ ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 128322 INFO (threadpool-153-thread-2) [ ] o.a.s.s.IndexSchema [null] Schema name=minimal
[junit4] 2> 128323 INFO (threadpool-153-thread-2) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 128324 INFO (TEST-TestManagedSchemaThreadSafety.testThreadSafety-seed#[714F0B54C3F17EDF]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testThreadSafety
[junit4] 2> 128324 INFO (SUITE-TestManagedSchemaThreadSafety-seed#[714F0B54C3F17EDF]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42912 42912
[junit4] 2> 128339 INFO (Thread-207) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42912 42912
[junit4] 2> 128340 INFO (SUITE-TestManagedSchemaThreadSafety-seed#[714F0B54C3F17EDF]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> Oct 02, 2016 10:54:54 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=620, maxMBSortInHeap=7.08769450196648, sim=RandomSimilarity(queryNorm=false): {}, locale=sg-CF, timezone=Etc/GMT-7
[junit4] 2> NOTE: Linux 4.4.0-36-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=83229368,total=186474496
[junit4] 2> NOTE: All tests run in this JVM: [CurrencyFieldXmlFileTest, CircularListTest, CollectionReloadTest, TestPhraseSuggestions, SimpleFacetsTest, SolrIndexConfigTest, SuggesterWFSTTest, FullSolrCloudDistribCmdsTest, SimpleCollectionCreateDeleteTest, CursorMarkTest, TestManagedSchemaThreadSafety]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestManagedSchemaThreadSafety -Dtests.seed=714F0B54C3F17EDF -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sg-CF -Dtests.timezone=Etc/GMT-7 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J2 | TestManagedSchemaThreadSafety (suite) <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: Clean up static fields (in @AfterClass?) and null them, your test still has references to classes of which the sizes cannot be measured due to security restrictions or Java 9 module encapsulation:
[junit4] > - private static org.apache.solr.cloud.ZkTestServer org.apache.solr.schema.TestManagedSchemaThreadSafety.zkServer
[junit4] > at __randomizedtesting.SeedInfo.seed([714F0B54C3F17EDF]:0)
[junit4] > at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
[junit4] > Caused by: java.lang.IllegalStateException: Unable to access 'private final sun.nio.ch.FileDispatcher sun.nio.ch.FileChannelImpl.nd' to estimate memory usage
[junit4] > ... 10 more
[junit4] > Caused by: java.lang.reflect.InaccessibleObjectException: Unable to make member of class sun.nio.ch.FileChannelImpl accessible: module java.base does not export sun.nio.ch to unnamed module @5a3f96
[junit4] > at jdk.internal.reflect.Reflection.throwInaccessibleObjectException(java.base@9-ea/Reflection.java:414)
[junit4] > at java.lang.reflect.AccessibleObject.checkCanSetAccessible(java.base@9-ea/AccessibleObject.java:174)
[junit4] > at java.lang.reflect.Field.checkCanSetAccessible(java.base@9-ea/Field.java:171)
[junit4] > at java.lang.reflect.Field.setAccessible(java.base@9-ea/Field.java:165)
[junit4] > at java.security.AccessController.doPrivileged(java.base@9-ea/Native Method)
[junit4] > ... 13 more
[junit4] Completed [32/636 (1!)] on J2 in 1.45s, 1 test, 1 failure <<< FAILURES!
[...truncated 51787 lines...]
[JENKINS-EA] Lucene-Solr-master-Linux (32bit/jdk-9-ea+136) - Build
# 17958 - Still unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17958/
Java: 32bit/jdk-9-ea+136 -server -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenRenew
Error Message:
expected:<200> but was:<403>
Stack Trace:
java.lang.AssertionError: expected:<200> but was:<403>
at __randomizedtesting.SeedInfo.seed([838F48D068278884:B414BCCE50EB5520]: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.renewDelegationToken(TestSolrCloudWithDelegationTokens.java:127)
at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.verifyDelegationTokenRenew(TestSolrCloudWithDelegationTokens.java:303)
at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenRenew(TestSolrCloudWithDelegationTokens.java:320)
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
Build Log:
[...truncated 12119 lines...]
[junit4] Suite: org.apache.solr.cloud.TestSolrCloudWithDelegationTokens
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSolrCloudWithDelegationTokens_838F48D068278884-001/init-core-data-001
[junit4] 2> 1796982 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[838F48D068278884]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1796984 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[838F48D068278884]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1796984 INFO (Thread-3673) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1796984 INFO (Thread-3673) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1797085 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[838F48D068278884]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:44101
[junit4] 2> 1797088 INFO (jetty-launcher-2779-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1797088 INFO (jetty-launcher-2779-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1797089 INFO (jetty-launcher-2779-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1895f56{/solr,null,AVAILABLE}
[junit4] 2> 1797089 INFO (jetty-launcher-2779-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@10c1c1b{/solr,null,AVAILABLE}
[junit4] 2> 1797090 INFO (jetty-launcher-2779-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@894ec0{HTTP/1.1,[http/1.1]}{127.0.0.1:38575}
[junit4] 2> 1797090 INFO (jetty-launcher-2779-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@5649dd{HTTP/1.1,[http/1.1]}{127.0.0.1:34563}
[junit4] 2> 1797090 INFO (jetty-launcher-2779-thread-1) [ ] o.e.j.s.Server Started @1799262ms
[junit4] 2> 1797091 INFO (jetty-launcher-2779-thread-2) [ ] o.e.j.s.Server Started @1799262ms
[junit4] 2> 1797091 INFO (jetty-launcher-2779-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38575}
[junit4] 2> 1797091 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34563}
[junit4] 2> 1797091 INFO (jetty-launcher-2779-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1797091 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1797091 INFO (jetty-launcher-2779-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1797091 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1797091 INFO (jetty-launcher-2779-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1797091 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1797091 INFO (jetty-launcher-2779-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-03T08:25:52.442531Z
[junit4] 2> 1797091 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-03T08:25:52.442575Z
[junit4] 2> 1797092 INFO (jetty-launcher-2779-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1797092 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1797096 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 0x15789a62fd00001, 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(java.base@9-ea/Thread.java:843)
[junit4] 2> 1797097 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44101/solr
[junit4] 2> 1797097 INFO (jetty-launcher-2779-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44101/solr
[junit4] 2> 1797108 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34563_solr
[junit4] 2> 1797108 INFO (jetty-launcher-2779-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38575_solr
[junit4] 2> 1797109 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.c.Overseer Overseer (id=96697263871492101-127.0.0.1:34563_solr-n_0000000000) starting
[junit4] 2> 1797109 INFO (zkCallback-2790-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1797110 INFO (zkCallback-2789-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1797114 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34563_solr
[junit4] 2> 1797115 INFO (zkCallback-2790-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1797115 INFO (zkCallback-2789-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1797133 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 1797137 INFO (jetty-launcher-2779-thread-1) [ ] o.a.s.c.CoreContainer Authentication plugin class obtained from system property 'authenticationPlugin': org.apache.solr.security.HttpParamDelegationTokenPlugin
[junit4] 2> 1797154 INFO (jetty-launcher-2779-thread-1) [ ] 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> 1797154 INFO (jetty-launcher-2779-thread-2) [ ] 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> 1797243 INFO (jetty-launcher-2779-thread-1) [ ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 1797243 INFO (jetty-launcher-2779-thread-2) [ ] o.a.c.f.i.CuratorFrameworkImpl Starting
[junit4] 2> 1797258 INFO (jetty-launcher-2779-thread-1-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 1797258 INFO (jetty-launcher-2779-thread-2-EventThread) [ ] o.a.c.f.s.ConnectionStateManager State change: CONNECTED
[junit4] 2> 1797415 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSolrCloudWithDelegationTokens_838F48D068278884-001/tempDir-001/node2/.
[junit4] 2> 1797415 INFO (jetty-launcher-2779-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSolrCloudWithDelegationTokens_838F48D068278884-001/tempDir-001/node1/.
[junit4] 2> 1797439 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancel
[junit4] 2> 1797847 ERROR (qtp2259171-15982) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_1]
[junit4] 2> 1797877 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1797880 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1797893 ERROR (qtp5472696-15997) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_2]
[junit4] 2> 1797898 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1797900 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1797900 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancel-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancel
[junit4] 2> 1797919 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenew
[junit4] 2> 1797930 WARN (qtp2259171-15992) [ ] o.a.h.s.a.s.AuthenticationFilter Authentication exception: org.apache.hadoop.security.AccessControlException: bar is trying to renew a token with wrong password
[junit4] 2> 1797931 WARN (qtp2259171-15992) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 1797931 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1797932 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenew-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenew
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrCloudWithDelegationTokens -Dtests.method=testDelegationTokenRenew -Dtests.seed=838F48D068278884 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=pt-AO -Dtests.timezone=Asia/Dubai -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.03s J0 | TestSolrCloudWithDelegationTokens.testDelegationTokenRenew <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<200> but was:<403>
[junit4] > at __randomizedtesting.SeedInfo.seed([838F48D068278884:B414BCCE50EB5520]:0)
[junit4] > at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.renewDelegationToken(TestSolrCloudWithDelegationTokens.java:127)
[junit4] > at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.verifyDelegationTokenRenew(TestSolrCloudWithDelegationTokens.java:303)
[junit4] > at org.apache.solr.cloud.TestSolrCloudWithDelegationTokens.testDelegationTokenRenew(TestSolrCloudWithDelegationTokens.java:320)
[junit4] > at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
[junit4] > at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
[junit4] > at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
[junit4] 2> 1797947 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenSolrClient
[junit4] 2> 1797957 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1797966 INFO (qtp2259171-15986) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVeJpjOTigFXrbK3kwQEFGc17h0BIGzzYOjfCg_X3fSI_QiZB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1797967 INFO (qtp2259171-15986) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVeJpjOTigFXrbK3kwQEFGc17h0BIGzzYOjfCg_X3fSI_QiZB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1797967 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenSolrClient-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenSolrClient
[junit4] 2> 1797984 INFO (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZNodePaths
[junit4] 2> 1797993 INFO (TEST-TestSolrCloudWithDelegationTokens.testZNodePaths-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZNodePaths
[junit4] 2> 1798008 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenCancelFail
[junit4] 2> 1798020 ERROR (qtp5472696-15993) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_6]
[junit4] 2> 1798021 ERROR (qtp2259171-15996) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_6]
[junit4] 2> 1798030 ERROR (qtp5472696-15997) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_7]
[junit4] 2> 1798031 ERROR (qtp2259171-15982) [ ] o.a.h.s.t.d.ZKDelegationTokenSecretManager No node in path [/ZKDTSMTokensRoot/DT_7]
[junit4] 2> 1798031 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenCancelFail-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenCancelFail
[junit4] 2> 1798046 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenVerify
[junit4] 2> 1798056 INFO (qtp2259171-15986) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVeJpjP2igFXrbK39ggEFMZ_78HLi1QgKQC72VZ-HUtWimPsB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1798056 INFO (qtp2259171-15986) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVeJpjP2igFXrbK39ggEFMZ_78HLi1QgKQC72VZ-HUtWimPsB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1798058 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1798059 INFO (qtp5472696-15993) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :list with params action=LIST&delegation=GgADYmFyA2JhcgCKAVeJpjP2igFXrbK39ggEFMZ_78HLi1QgKQC72VZ-HUtWimPsB3NvbHItZHQA&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1798060 INFO (qtp5472696-15993) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=LIST&delegation=GgADYmFyA2JhcgCKAVeJpjP2igFXrbK39ggEFMZ_78HLi1QgKQC72VZ-HUtWimPsB3NvbHItZHQA&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1798062 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1798063 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenVerify-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenVerify
[junit4] 2> 1798078 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationTokenRenewFail
[junit4] 2> 1798086 WARN (qtp2259171-15986) [ ] 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> 1798087 WARN (qtp2259171-15986) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 1798087 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1798088 WARN (qtp5472696-15997) [ ] 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> 1798088 WARN (qtp5472696-15997) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 1798089 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1798094 WARN (qtp2259171-15994) [ ] 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> 1798095 WARN (qtp2259171-15994) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 1798095 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1798096 WARN (qtp5472696-15995) [ ] 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> 1798096 WARN (qtp5472696-15995) [ ] o.a.s.s.KerberosPlugin Could not find org.apache.solr.security.kerberosplugin.requestcontinues
[junit4] 2> 1798096 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth=; Path=/; Domain=127.0.0.1; Expires=quinta, 01-jan-1970 00:00:00 GMT; HttpOnly". Invalid 'expires' attribute: quinta, 01-jan-1970 00:00:00 GMT
[junit4] 2> 1798096 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationTokenRenewFail-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationTokenRenewFail
[junit4] 2> 1798112 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDelegationOtherOp
[junit4] 2> 1798115 INFO (qtp2259171-15986) [ ] 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> 1798115 INFO (qtp2259171-15986) [ ] 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> 1798115 WARN (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[838F48D068278884]) [ ] o.a.h.c.p.ResponseProcessCookies Invalid cookie header: "Set-Cookie: hadoop.auth="u=bar&p=bar&t=test&e=1475519153465&s=Rupru6Zfx5COkO/BA1LHzPzAY/U="; Path=/; Domain=127.0.0.1; Expires=segunda, 03-out-2016 18:25:53 GMT; HttpOnly". Invalid 'expires' attribute: segunda, 03-out-2016 18:25:53 GMT
[junit4] 2> 1798116 INFO (TEST-TestSolrCloudWithDelegationTokens.testDelegationOtherOp-seed#[838F48D068278884]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDelegationOtherOp
[junit4] 2> 1798116 INFO (jetty-launcher-2779-thread-2) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@5649dd{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1798116 INFO (jetty-launcher-2779-thread-1) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@894ec0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1798116 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2534108
[junit4] 2> 1798116 INFO (jetty-launcher-2779-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=7941692
[junit4] 2> 1798118 INFO (jetty-launcher-2779-thread-2) [ ] o.a.s.c.Overseer Overseer (id=96697263871492101-127.0.0.1:34563_solr-n_0000000000) closing
[junit4] 2> 1798118 INFO (OverseerStateUpdate-96697263871492101-127.0.0.1:34563_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34563_solr
[junit4] 2> 1798118 WARN (zkCallback-2790-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1798118 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 0x15789a62fd00005, 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(java.base@9-ea/Thread.java:843)
[junit4] 2> 1798118 ERROR (Thread[Thread-3692,5,TGRP-TestSolrCloudWithDelegationTokens]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 1798126 INFO (jetty-launcher-2779-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@10c1c1b{/solr,null,UNAVAILABLE}
[junit4] 2> 1799618 WARN (zkCallback-2789-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1799618 ERROR (Thread[Thread-3691,5,TGRP-TestSolrCloudWithDelegationTokens]) [ ] o.a.h.s.t.d.AbstractDelegationTokenSecretManager ExpiredTokenRemover received java.lang.InterruptedException: sleep interrupted
[junit4] 2> 1799619 INFO (jetty-launcher-2779-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1895f56{/solr,null,UNAVAILABLE}
[junit4] 2> 1799620 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[838F48D068278884]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44101 44101
[junit4] 2> 1799775 INFO (Thread-3673) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44101 44101
[junit4] 2> 1799775 WARN (Thread-3673) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[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/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/clusterstate.json
[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/ZKDTSMSeqNumRoot
[junit4] 2> 2 /solr/security/zkdtsm/ZKDTSMRoot/ZKDTSMTokensRoot/DT_10
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/live_nodes
[junit4] 2> 2 /solr/collections
[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> 1799775 INFO (SUITE-TestSolrCloudWithDelegationTokens-seed#[838F48D068278884]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestSolrCloudWithDelegationTokens_838F48D068278884-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=1612, maxMBSortInHeap=6.371556367550253, sim=RandomSimilarity(queryNorm=false): {}, locale=pt-AO, timezone=Asia/Dubai
[junit4] 2> NOTE: Linux 4.4.0-36-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=85955904,total=422641664
[junit4] 2> NOTE: All tests run in this JVM: [VMParamsZkACLAndCredentialsProvidersTest, SpatialRPTFieldTypeTest, SolrCLIZkUtilsTest, SolrRequestParserTest, ActionThrottleTest, TestFieldCacheReopen, TestReqParamsAPI, TestCSVLoader, TestAddFieldRealTimeGet, DocumentAnalysisRequestHandlerTest, SharedFSAutoReplicaFailoverTest, TestSolrDeletionPolicy2, TestBackupRepositoryFactory, TestFreeTextSuggestions, SmileWriterTest, ClusterStateTest, TestLFUCache, CdcrRequestHandlerTest, BasicZkTest, HdfsRestartWhileUpdatingTest, TestJoin, AsyncCallRequestStatusResponseTest, XsltUpdateRequestHandlerTest, TestRangeQuery, TestStressLucene, TestSolrCoreProperties, TestSolrConfigHandlerCloud, TestOrdValues, TestImplicitCoreProperties, CollectionsAPISolrJTest, ConnectionManagerTest, TestExclusionRuleCollectionAccess, TestUniqueKeyFieldResource, UniqFieldsUpdateProcessorFactoryTest, TestPerFieldSimilarityWithDefaultOverride, TestRandomFlRTGCloud, OverseerCollectionConfigSetProcessorTest, TestLMJelinekMercerSimilarityFactory, TestSolrCoreSnapshots, TestQueryWrapperFilter, CoreAdminRequestStatusTest, TestLRUStatsCache, HdfsChaosMonkeyNothingIsSafeTest, TestLocalFSCloudBackupRestore, TestSolrConfigHandler, ParsingFieldUpdateProcessorsTest, TestFastWriter, ZkStateWriterTest, TestFoldingMultitermQuery, PluginInfoTest, GraphQueryTest, CopyFieldTest, TestRemoteStreaming, TestUninvertingReader, TestSchemaResource, MultiThreadedOCPTest, BlockDirectoryTest, TestLeaderElectionZkExpiry, PingRequestHandlerTest, HdfsNNFailoverTest, TestReplicationHandler, CachingDirectoryFactoryTest, TolerantUpdateProcessorTest, UUIDFieldTest, TestLRUCache, TestDFRSimilarityFactory, PolyFieldTest, TestFaceting, DateMathParserTest, AnalyticsMergeStrategyTest, QueryElevationComponentTest, TestObjectReleaseTracker, TestHighlightDedupGrouping, TestSearcherReuse, SpatialHeatmapFacetsTest, LeaderElectionIntegrationTest, TestRTGBase, ZkNodePropsTest, TestRawTransformer, TestRealTimeGet, SearchHandlerTest, TestSlowCompositeReaderWrapper, TestCopyFieldCollectionResource, TestComplexPhraseQParserPlugin, SpellPossibilityIteratorTest, ReturnFieldsTest, TestLazyCores, SecurityConfHandlerTest, TestCodecSupport, DistributedFacetPivotSmallTest, FileBasedSpellCheckerTest, SSLMigrationTest, TestAnalyzedSuggestions, TestMacroExpander, MinimalSchemaTest, TestJmxIntegration, RecoveryAfterSoftCommitTest, DistributedQueryComponentOptimizationTest, BasicDistributedZkTest, CdcrUpdateLogTest, DateRangeFieldTest, CurrencyFieldOpenExchangeTest, SyncSliceTest, TestRandomDVFaceting, DistributedSpellCheckComponentTest, TestReload, DistributedTermsComponentTest, SolrCoreTest, PeerSyncTest, ConvertedLegacyTest, TestFiltering, TestTrie, SuggesterWFSTTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestUpdate, TestAtomicUpdateErrorCases, QueryEqualityTest, StatelessScriptUpdateProcessorFactoryTest, DisMaxRequestHandlerTest, TestQueryTypes, TestOmitPositions, DocumentBuilderTest, TermVectorComponentTest, FastVectorHighlighterTest, TestQuerySenderListener, TestQuerySenderNoQuery, ResponseLogComponentTest, MultiTermTest, TestDocSet, TestPhraseSuggestions, TestSuggestSpellingConverter, SpellingQueryConverterTest, SliceStateTest, DistributedIntervalFacetingTest, TestTolerantSearch, ConnectionReuseTest, CdcrReplicationHandlerTest, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, CollectionTooManyReplicasTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, DeleteStatusTest, DistribCursorPagingTest, DistributedVersionInfoTest, LeaderFailureAfterFreshStartTest, LeaderInitiatedRecoveryOnCommitTest, LeaderInitiatedRecoveryOnShardRestartTest, MigrateRouteKeyTest, OverseerStatusTest, SharedFSAutoReplicaFailoverUtilsTest, SolrCloudExampleTest, TestCloudPivotFacet, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestLeaderRecoverFromLogOnStartup, TestMiniSolrCloudClusterSSL, TestRandomRequestDistribution, TestReplicaProperties, TestSizeLimitedDistributedMap, TestSolrCloudWithDelegationTokens]
[junit4] Completed [495/636 (1!)] on J0 in 2.81s, 8 tests, 1 failure <<< FAILURES!
[...truncated 50285 lines...]
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk1.8.0_102) - Build #
17957 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17957/
Java: 64bit/jdk1.8.0_102 -XX:+UseCompressedOops -XX:+UseG1GC
All tests passed
Build Log:
[...truncated 13242 lines...]
[junit4] JVM J1: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/temp/junit4-J1-20161003_052806_137.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: Java heap space
[junit4] Dumping heap to /home/jenkins/workspace/Lucene-Solr-master-Linux/heapdumps/java_pid3460.hprof ...
[junit4] Heap dump file created [603454506 bytes in 2.966 secs]
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J1: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/temp/junit4-J1-20161003_052806_137.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "aliveCheckExecutor-247-thread-1"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "qtp1784680434-1554"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "Connection evictor"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "org.eclipse.jetty.server.session.HashSessionManager@3feaf9eTimer"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "org.eclipse.jetty.server.session.HashSessionManager@22dcec18Timer"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "org.eclipse.jetty.server.session.HashSessionManager@5174244fTimer"
[junit4]
[junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "SUITE-TestLBHttpSolrClient-seed#[92296505D1CEEF69]"
[junit4] WARN: JVM out of memory. -> java.lang.OutOfMemoryError: Java heap space
[junit4] <<< JVM J1: EOF ----
[...truncated 4 lines...]
[junit4] ERROR: JVM J1 ended with an exception, command line: /home/jenkins/tools/java/64bit/jdk1.8.0_102/jre/bin/java -XX:+UseCompressedOops -XX:+UseG1GC -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/home/jenkins/workspace/Lucene-Solr-master-Linux/heapdumps -ea -esa -Dtests.prefix=tests -Dtests.seed=92296505D1CEEF69 -Xmx512M -Dtests.iters= -Dtests.verbose=false -Dtests.infostream=false -Dtests.codec=random -Dtests.postingsformat=random -Dtests.docvaluesformat=random -Dtests.locale=random -Dtests.timezone=random -Dtests.directory=random -Dtests.linedocsfile=europarl.lines.txt.gz -Dtests.luceneMatchVersion=7.0.0 -Dtests.cleanthreads=perClass -Djava.util.logging.config.file=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/tools/junit4/logging.properties -Dtests.nightly=false -Dtests.weekly=false -Dtests.monster=false -Dtests.slow=true -Dtests.asserts=true -Dtests.multiplier=3 -DtempDir=./temp -Djava.io.tmpdir=./temp -Djunit4.tempDir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/temp -Dcommon.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene -Dclover.db.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/clover/db -Djava.security.policy=/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/tools/junit4/solr-tests.policy -Dtests.LUCENE_VERSION=7.0.0 -Djetty.testMode=1 -Djetty.insecurerandom=1 -Dsolr.directoryFactory=org.apache.solr.core.MockDirectoryFactory -Djava.awt.headless=true -Djdk.map.althashing.threshold=0 -Djunit4.childvm.cwd=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1 -Djunit4.childvm.id=1 -Djunit4.childvm.count=3 -Dtests.leaveTemporary=false -Dtests.filterstacks=true -Dtests.disableHdfs=true -Djava.security.manager=org.apache.lucene.util.TestSecurityManager -Dfile.encoding=ISO-8859-1 -classpath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/classes/test:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/test-lib/slf4j-log4j12-1.7.7.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-test-framework/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/src/test-files:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/test-framework/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/codecs/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/commons-io-2.5.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/guava-14.0.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/httpclient-4.4.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/httpcore-4.4.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/httpmime-4.4.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/jackson-annotations-2.5.4.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/jackson-core-2.5.4.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/jackson-databind-2.5.4.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/jcl-over-slf4j-1.7.7.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/noggit-0.6.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/slf4j-api-1.7.7.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/stax2-api-3.1.4.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/woodstox-core-asl-4.4.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solrj/lib/zookeeper-3.4.6.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/test-framework/lib/junit-4.10.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.3.4.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/common/lucene-analyzers-common-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/codecs/lucene-codecs-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/backward-codecs/lucene-backward-codecs-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/highlighter/lucene-highlighter-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/memory/lucene-memory-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/misc/lucene-misc-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/spatial-extras/lucene-spatial-extras-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/expressions/lucene-expressions-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/suggest/lucene-suggest-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/grouping/lucene-grouping-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queries/lucene-queries-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/queryparser/lucene-queryparser-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/join/lucene-join-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/sandbox/lucene-sandbox-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/classification/lucene-classification-7.0.0-SNAPSHOT.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/antlr4-runtime-4.5.1-1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/asm-5.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/asm-commons-5.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/caffeine-1.0.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/commons-cli-1.2.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/commons-codec-1.10.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/commons-collections-3.2.2.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/commons-configuration-1.6.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/commons-exec-1.3.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/commons-fileupload-1.3.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/commons-io-2.5.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/commons-lang-2.6.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/curator-client-2.8.0.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/curator-framework-2.8.0.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/curator-recipes-2.8.0.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/dom4j-1.6.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/guava-14.0.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/hadoop-annotations-2.7.2.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/hadoop-auth-2.7.2.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/hadoop-common-2.7.2.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/hadoop-hdfs-2.7.2.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/hppc-0.7.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/htrace-core-3.2.0-incubating.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/jackson-core-2.5.4.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/jackson-core-asl-1.9.13.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/jackson-dataformat-smile-2.5.4.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/jackson-mapper-asl-1.9.13.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/jcl-over-slf4j-1.7.7.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/joda-time-2.2.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/log4j-1.2.17.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/org.restlet-2.3.0.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/org.restlet.ext.servlet-2.3.0.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/presto-parser-0.122.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/protobuf-java-2.5.0.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/slf4j-log4j12-1.7.7.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/slice-0.10.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/spatial4j-0.6.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/lib/t-digest-3.1.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/javax.servlet-api-3.1.0.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-continuation-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-deploy-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-http-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-io-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-jmx-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-rewrite-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-security-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-server-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-servlet-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-servlets-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-util-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-webapp-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/lib/jetty-xml-9.3.8.v20160314.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/classes/java:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/example/resources:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/var/lib/jenkins/.ant/lib/ivy-2.3.0.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/home/jenkins/tools/java/64bit/jdk1.8.0_102/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.3.4.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/temp/junit4-J1-20161003_052806_137.events @/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/temp/junit4-J1-20161003_052806_137.suites -stdin
[junit4] ERROR: JVM J1 ended with an exception: Forked JVM ran out of memory. See process stdout at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/temp/junit4-J1-20161003_052806_137.sysout See process stderr at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/temp/junit4-J1-20161003_052806_137.syserr
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1481)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952)
[junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] at java.lang.Thread.run(Thread.java:745)
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:763: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:707: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build.xml:265: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/common-build.xml:536: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1443: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:984: At least one slave process threw an exception, first: Forked JVM ran out of memory. See process stdout at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/temp/junit4-J1-20161003_052806_137.sysout See process stderr at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/temp/junit4-J1-20161003_052806_137.syserr
Total time: 59 minutes 56 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk1.8.0_102) - Build #
17956 - Failure!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17956/
Java: 64bit/jdk1.8.0_102 -XX:-UseCompressedOops -XX:+UseParallelGC
3 tests failed.
FAILED: org.apache.solr.client.solrj.TestLBHttpSolrClient.testReliability
Error Message:
GC overhead limit exceeded
Stack Trace:
java.lang.OutOfMemoryError: GC overhead limit exceeded
at __randomizedtesting.SeedInfo.seed([15E00891F2D26E64:D428D5D753B4BFCD]:0)
at sun.security.ssl.InputRecord.<init>(InputRecord.java:93)
at sun.security.ssl.HandshakeInStream.<init>(HandshakeInStream.java:63)
at sun.security.ssl.Handshaker.activate(Handshaker.java:535)
at sun.security.ssl.SSLSocketImpl.kickstartHandshake(SSLSocketImpl.java:1482)
at sun.security.ssl.SSLSocketImpl.performInitialHandshake(SSLSocketImpl.java:1351)
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:1403)
at sun.security.ssl.SSLSocketImpl.startHandshake(SSLSocketImpl.java:1387)
at org.apache.http.conn.ssl.SSLConnectionSocketFactory.createLayeredSocket(SSLConnectionSocketFactory.java:394)
at org.apache.http.conn.ssl.SSLConnectionSocketFactory.connectSocket(SSLConnectionSocketFactory.java:353)
at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134)
at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353)
at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:513)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:578)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957)
at org.apache.solr.client.solrj.TestLBHttpSolrClient.waitForServer(TestLBHttpSolrClient.java:237)
at org.apache.solr.client.solrj.TestLBHttpSolrClient.testReliability(TestLBHttpSolrClient.java:225)
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)
FAILED: org.apache.solr.cloud.TestTolerantUpdateProcessorCloud.testVariousDeletesViaShard2NonLeaderClient
Error Message:
Timeout occured while waiting response from server at: https://127.0.0.1:34257/solr/test_col
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: https://127.0.0.1:34257/solr/test_col
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:619)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:166)
at org.apache.solr.cloud.TestTolerantUpdateProcessorCloud.testVariousDeletes(TestTolerantUpdateProcessorCloud.java:356)
at org.apache.solr.cloud.TestTolerantUpdateProcessorCloud.testVariousDeletesViaShard2NonLeaderClient(TestTolerantUpdateProcessorCloud.java:297)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:170)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at sun.security.ssl.InputRecord.readFully(InputRecord.java:465)
at sun.security.ssl.InputRecord.read(InputRecord.java:503)
at sun.security.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:973)
at sun.security.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:930)
at sun.security.ssl.AppInputStream.read(AppInputStream.java:105)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:139)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:155)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:284)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:165)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:167)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:513)
... 45 more
FAILED: org.apache.solr.handler.component.DistributedFacetPivotLongTailTest.test
Error Message:
GC overhead limit exceeded
Stack Trace:
java.lang.OutOfMemoryError: GC overhead limit exceeded
at __randomizedtesting.SeedInfo.seed([70B91B9A017B9D31:F8ED2440AF87F0C9]:0)
at java.io.ByteArrayOutputStream.<init>(ByteArrayOutputStream.java:77)
at sun.security.ssl.OutputRecord.<init>(OutputRecord.java:94)
at sun.security.ssl.OutputRecord.<init>(OutputRecord.java:105)
at sun.security.ssl.AppOutputStream.<init>(AppOutputStream.java:52)
at sun.security.ssl.SSLSocketImpl.init(SSLSocketImpl.java:636)
at sun.security.ssl.SSLSocketImpl.<init>(SSLSocketImpl.java:567)
at sun.security.ssl.SSLSocketFactoryImpl.createSocket(SSLSocketFactoryImpl.java:110)
at org.apache.http.conn.ssl.SSLConnectionSocketFactory.createLayeredSocket(SSLConnectionSocketFactory.java:363)
at org.apache.http.conn.ssl.SSLConnectionSocketFactory.connectSocket(SSLConnectionSocketFactory.java:353)
at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134)
at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353)
at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:513)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:152)
at org.apache.solr.handler.component.DistributedFacetPivotLongTailTest.test(DistributedFacetPivotLongTailTest.java:71)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
Build Log:
[...truncated 12295 lines...]
[junit4] Suite: org.apache.solr.handler.component.DistributedFacetPivotLongTailTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/init-core-data-001
[junit4] 2> 1784827 INFO (SUITE-DistributedFacetPivotLongTailTest-seed#[70B91B9A017B9D31]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 1784828 INFO (SUITE-DistributedFacetPivotLongTailTest-seed#[70B91B9A017B9D31]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1784883 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/control/cores/collection1
[junit4] 2> 1784884 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1784885 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@954a64b{/,null,AVAILABLE}
[junit4] 2> 1784888 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.ServerConnector Started ServerConnector@691e2d0{SSL,[ssl, http/1.1]}{127.0.0.1:42522}
[junit4] 2> 1784888 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.Server Started @1786965ms
[junit4] 2> 1784888 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=42522, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/control/cores}
[junit4] 2> 1784888 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1784888 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1784888 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1784888 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-03T02:20:05.178Z
[junit4] 2> 1784889 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/control/solr.xml
[junit4] 2> 1784895 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/control/cores
[junit4] 2> 1784895 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1784895 INFO (coreLoadExecutor-9933-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1784915 INFO (coreLoadExecutor-9933-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1784943 INFO (coreLoadExecutor-9933-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1785025 WARN (coreLoadExecutor-9933-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1785037 INFO (coreLoadExecutor-9933-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1785043 INFO (coreLoadExecutor-9933-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 1785044 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 1785044 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3643b0b4
[junit4] 2> 1785044 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1785054 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1785054 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1785055 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1785055 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1785055 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=91.416015625, floorSegmentMB=2.1640625, forceMergeDeletesPctAllowed=13.100952225873444, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1785055 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@268721c0[collection1] main]
[junit4] 2> 1785055 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1785126 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1785324 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1785324 INFO (searcherExecutor-9934-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1785325 INFO (searcherExecutor-9934-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1785325 INFO (searcherExecutor-9934-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1785325 INFO (searcherExecutor-9934-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1785325 INFO (searcherExecutor-9934-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1785325 INFO (coreLoadExecutor-9933-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547133209138954240
[junit4] 2> 1785325 INFO (searcherExecutor-9934-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1785325 INFO (searcherExecutor-9934-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1785326 INFO (searcherExecutor-9934-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1785326 INFO (searcherExecutor-9934-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1785327 INFO (searcherExecutor-9934-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@268721c0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1785451 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1785452 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1785453 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@273c1ac4{/,null,AVAILABLE}
[junit4] 2> 1785454 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.ServerConnector Started ServerConnector@62c46a27{SSL,[ssl, http/1.1]}{127.0.0.1:41724}
[junit4] 2> 1785454 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.Server Started @1787531ms
[junit4] 2> 1785454 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=41724, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard0/cores}
[junit4] 2> 1785455 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1785455 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1785455 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1785455 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-03T02:20:05.745Z
[junit4] 2> 1785455 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard0/solr.xml
[junit4] 2> 1785461 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard0/cores
[junit4] 2> 1785461 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1785462 INFO (coreLoadExecutor-9944-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1785483 INFO (coreLoadExecutor-9944-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1785503 INFO (coreLoadExecutor-9944-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1785597 WARN (coreLoadExecutor-9944-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1785599 INFO (coreLoadExecutor-9944-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1785604 INFO (coreLoadExecutor-9944-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 1785604 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 1785604 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3643b0b4
[junit4] 2> 1785604 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1785615 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1785615 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1785615 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1785615 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1785615 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=91.416015625, floorSegmentMB=2.1640625, forceMergeDeletesPctAllowed=13.100952225873444, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1785616 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@399db35a[collection1] main]
[junit4] 2> 1785616 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1785668 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1785813 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1785813 INFO (searcherExecutor-9945-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1785813 INFO (searcherExecutor-9945-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1785813 INFO (searcherExecutor-9945-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1785813 INFO (searcherExecutor-9945-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1785813 INFO (coreLoadExecutor-9944-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547133209650659328
[junit4] 2> 1785813 INFO (searcherExecutor-9945-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1785814 INFO (searcherExecutor-9945-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1785814 INFO (searcherExecutor-9945-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1785814 INFO (searcherExecutor-9945-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1785814 INFO (searcherExecutor-9945-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1785815 INFO (searcherExecutor-9945-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@399db35a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1786016 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard1/cores/collection1
[junit4] 2> 1786017 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1786017 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@22267324{/,null,AVAILABLE}
[junit4] 2> 1786019 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.ServerConnector Started ServerConnector@9f17b7b{SSL,[ssl, http/1.1]}{127.0.0.1:35387}
[junit4] 2> 1786019 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.Server Started @1788096ms
[junit4] 2> 1786019 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=35387, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard1/cores}
[junit4] 2> 1786020 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1786020 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1786020 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1786020 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-03T02:20:06.310Z
[junit4] 2> 1786020 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard1/solr.xml
[junit4] 2> 1786026 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard1/cores
[junit4] 2> 1786026 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1786027 INFO (coreLoadExecutor-9955-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1786057 INFO (coreLoadExecutor-9955-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1786076 INFO (coreLoadExecutor-9955-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1786182 WARN (coreLoadExecutor-9955-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1786184 INFO (coreLoadExecutor-9955-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1786188 INFO (coreLoadExecutor-9955-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 1786188 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard1/cores/collection1/data/]
[junit4] 2> 1786188 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3643b0b4
[junit4] 2> 1786189 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1786199 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1786199 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1786199 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1786199 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1786200 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=91.416015625, floorSegmentMB=2.1640625, forceMergeDeletesPctAllowed=13.100952225873444, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1786200 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7648447d[collection1] main]
[junit4] 2> 1786200 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1786227 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1786337 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1786337 INFO (searcherExecutor-9956-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1786338 INFO (searcherExecutor-9956-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1786338 INFO (searcherExecutor-9956-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1786338 INFO (searcherExecutor-9956-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1786338 INFO (coreLoadExecutor-9955-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547133210201161728
[junit4] 2> 1786338 INFO (searcherExecutor-9956-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1786338 INFO (searcherExecutor-9956-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1786339 INFO (searcherExecutor-9956-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1786339 INFO (searcherExecutor-9956-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1786339 INFO (searcherExecutor-9956-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1786340 INFO (searcherExecutor-9956-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7648447d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1786599 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard2/cores/collection1
[junit4] 2> 1786600 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1786601 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@db461c8{/,null,AVAILABLE}
[junit4] 2> 1786603 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.ServerConnector Started ServerConnector@42591417{SSL,[ssl, http/1.1]}{127.0.0.1:35232}
[junit4] 2> 1786603 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.e.j.s.Server Started @1788680ms
[junit4] 2> 1786603 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=35232, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard2/cores}
[junit4] 2> 1786603 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 1786604 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1786604 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1786604 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-03T02:20:06.894Z
[junit4] 2> 1786604 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard2/solr.xml
[junit4] 2> 1786612 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard2/cores
[junit4] 2> 1786612 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1786613 INFO (coreLoadExecutor-9966-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1786639 INFO (coreLoadExecutor-9966-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1786662 INFO (coreLoadExecutor-9966-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1786792 WARN (coreLoadExecutor-9966-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1786795 INFO (coreLoadExecutor-9966-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1786803 INFO (coreLoadExecutor-9966-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 1786804 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.handler.component.DistributedFacetPivotLongTailTest_70B91B9A017B9D31-001/tempDir-001/shard2/cores/collection1/data/]
[junit4] 2> 1786804 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3643b0b4
[junit4] 2> 1786805 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1786820 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1786820 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1786820 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1786820 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1786820 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=91.416015625, floorSegmentMB=2.1640625, forceMergeDeletesPctAllowed=13.100952225873444, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1786821 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@18df2d2f[collection1] main]
[junit4] 2> 1786821 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1786843 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1786947 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1786948 INFO (searcherExecutor-9967-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1786949 INFO (coreLoadExecutor-9966-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547133210841841664
[junit4] 2> 1786950 INFO (searcherExecutor-9967-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1786950 INFO (searcherExecutor-9967-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1786950 INFO (searcherExecutor-9967-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1786950 INFO (searcherExecutor-9967-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1786951 INFO (searcherExecutor-9967-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1786951 INFO (searcherExecutor-9967-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1786951 INFO (searcherExecutor-9967-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1786951 INFO (searcherExecutor-9967-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1786952 INFO (searcherExecutor-9967-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@18df2d2f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1787115 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[70B91B9A017B9D31]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1787145 INFO (qtp1572913099-87351) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1547133211046313984)]} 0 0
[junit4] 2> 1787199 INFO (qtp549770350-87368) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1547133211101888512)]} 0 1
[junit4] 2> 1787230 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1547133211135442944)]} 0 1
[junit4] 2> 1787234 INFO (qtp1572913099-87358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1547133211139637248)]} 0 0
[junit4] 2> 1787238 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1547133211144880128)]} 0 0
[junit4] 2> 1787242 INFO (qtp1100876150-87392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1547133211149074432)]} 0 0
[junit4] 2> 1787245 INFO (qtp1572913099-87356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1547133211152220160)]} 0 0
[junit4] 2> 1787249 INFO (qtp549770350-87369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1547133211155365888)]} 0 0
[junit4] 2> 1787251 INFO (qtp1100876150-87392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1547133211158511616)]} 0 0
[junit4] 2> 1787253 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1547133211160608768)]} 0 0
[junit4] 2> 1787255 INFO (qtp549770350-87370) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1547133211162705920)]} 0 0
[junit4] 2> 1787257 INFO (qtp1100876150-87392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1547133211164803072)]} 0 0
[junit4] 2> 1787260 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1547133211167948800)]} 0 0
[junit4] 2> 1787262 INFO (qtp549770350-87370) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1547133211170045952)]} 0 0
[junit4] 2> 1787264 INFO (qtp1100876150-87392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1547133211172143104)]} 0 0
[junit4] 2> 1787266 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1547133211174240256)]} 0 0
[junit4] 2> 1787268 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1547133211176337408)]} 0 0
[junit4] 2> 1787271 INFO (qtp1100876150-87392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[18 (1547133211179483136)]} 0 0
[junit4] 2> 1787273 INFO (qtp1572913099-87354) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[19 (1547133211181580288)]} 0 0
[junit4] 2> 1787276 INFO (qtp549770350-87371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[20 (1547133211184726016)]} 0 0
[junit4] 2> 1787283 INFO (qtp1100876150-87391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[21 (1547133211192066048)]} 0 1
[junit4] 2> 1787285 INFO (qtp1572913099-87354) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[22 (1547133211194163200)]} 0 0
[junit4] 2> 1787288 INFO (qtp549770350-87369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[23 (1547133211197308928)]} 0 0
[junit4] 2> 1787291 INFO (qtp1100876150-87391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[24 (1547133211199406080)]} 0 0
[junit4] 2> 1787293 INFO (qtp1572913099-87354) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[25 (1547133211202551808)]} 0 0
[junit4] 2> 1787297 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[26 (1547133211205697536)]} 0 0
[junit4] 2> 1787299 INFO (qtp1100876150-87391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[27 (1547133211208843264)]} 0 0
[junit4] 2> 1787302 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[28 (1547133211211988992)]} 0 0
[junit4] 2> 1787304 INFO (qtp549770350-87369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[29 (1547133211214086144)]} 0 0
[junit4] 2> 1787307 INFO (qtp1100876150-87388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[30 (1547133211217231872)]} 0 0
[junit4] 2> 1787309 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[31 (1547133211219329024)]} 0 0
[junit4] 2> 1787311 INFO (qtp549770350-87369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[32 (1547133211221426176)]} 0 0
[junit4] 2> 1787313 INFO (qtp1100876150-87388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[33 (1547133211223523328)]} 0 0
[junit4] 2> 1787315 INFO (qtp1572913099-87356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[34 (1547133211225620480)]} 0 0
[junit4] 2> 1787318 INFO (qtp549770350-87369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[35 (1547133211227717632)]} 0 0
[junit4] 2> 1787320 INFO (qtp1100876150-87386) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[36 (1547133211230863360)]} 0 0
[junit4] 2> 1787322 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[37 (1547133211232960512)]} 0 0
[junit4] 2> 1787324 INFO (qtp549770350-87368) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[38 (1547133211235057664)]} 0 0
[junit4] 2> 1787326 INFO (qtp1100876150-87389) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[39 (1547133211237154816)]} 0 0
[junit4] 2> 1787329 INFO (qtp1572913099-87352) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[40 (1547133211240300544)]} 0 0
[junit4] 2> 1787333 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[41 (1547133211244494848)]} 0 0
[junit4] 2> 1787335 INFO (qtp1100876150-87386) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[42 (1547133211246592000)]} 0 0
[junit4] 2> 1787337 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[43 (1547133211248689152)]} 0 0
[junit4] 2> 1787344 INFO (qtp549770350-87374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[44 (1547133211256029184)]} 0 0
[junit4] 2> 1787346 INFO (qtp1100876150-87389) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[45 (1547133211258126336)]} 0 0
[junit4] 2> 1787348 INFO (qtp1572913099-87355) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[46 (1547133211260223488)]} 0 0
[junit4] 2> 1787351 INFO (qtp549770350-87371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[47 (1547133211263369216)]} 0 0
[junit4] 2> 1787355 INFO (qtp1100876150-87388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[48 (1547133211266514944)]} 0 1
[junit4] 2> 1787358 INFO (qtp1572913099-87352) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[49 (1547133211270709248)]} 0 0
[junit4] 2> 1787361 INFO (qtp549770350-87369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[50 (1547133211273854976)]} 0 0
[junit4] 2> 1787364 INFO (qtp1100876150-87388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[51 (1547133211277000704)]} 0 0
[junit4] 2> 1787367 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[52 (1547133211280146432)]} 0 0
[junit4] 2> 1787370 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[53 (1547133211283292160)]} 0 0
[junit4] 2> 1787372 INFO (qtp1100876150-87389) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[54 (1547133211285389312)]} 0 0
[junit4] 2> 1787374 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[55 (1547133211287486464)]} 0 0
[junit4] 2> 1787376 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[56 (1547133211289583616)]} 0 0
[junit4] 2> 1787379 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[57 (1547133211292729344)]} 0 0
[junit4] 2> 1787381 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[58 (1547133211294826496)]} 0 0
[junit4] 2> 1787383 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[59 (1547133211296923648)]} 0 0
[junit4] 2> 1787386 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[60 (1547133211300069376)]} 0 0
[junit4] 2> 1787388 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[61 (1547133211302166528)]} 0 0
[junit4] 2> 1787392 INFO (qtp549770350-87368) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[62 (1547133211305312256)]} 0 0
[junit4] 2> 1787394 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[63 (1547133211308457984)]} 0 0
[junit4] 2> 1787397 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[64 (1547133211311603712)]} 0 0
[junit4] 2> 1787400 INFO (qtp549770350-87374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[65 (1547133211314749440)]} 0 0
[junit4] 2> 1787403 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[66 (1547133211317895168)]} 0 0
[junit4] 2> 1787406 INFO (qtp1572913099-87351) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[67 (1547133211319992320)]} 0 0
[junit4] 2> 1787408 INFO (qtp549770350-87371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[68 (1547133211323138048)]} 0 0
[junit4] 2> 1787410 INFO (qtp1100876150-87389) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[69 (1547133211325235200)]} 0 0
[junit4] 2> 1787413 INFO (qtp1572913099-87356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[70 (1547133211328380928)]} 0 0
[junit4] 2> 1787416 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[71 (1547133211331526656)]} 0 0
[junit4] 2> 1787419 INFO (qtp1100876150-87386) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[72 (1547133211333623808)]} 0 0
[junit4] 2> 1787421 INFO (qtp1572913099-87354) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[73 (1547133211336769536)]} 0 0
[junit4] 2> 1787423 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[74 (1547133211338866688)]} 0 0
[junit4] 2> 1787425 INFO (qtp1100876150-87388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[75 (1547133211340963840)]} 0 0
[junit4] 2> 1787428 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[76 (1547133211344109568)]} 0 0
[junit4] 2> 1787431 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[77 (1547133211347255296)]} 0 0
[junit4] 2> 1787433 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[78 (1547133211349352448)]} 0 0
[junit4] 2> 1787436 INFO (qtp1572913099-87352) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[79 (1547133211351449600)]} 0 0
[junit4] 2> 1787439 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[80 (1547133211354595328)]} 0 0
[junit4] 2> 1787441 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[81 (1547133211356692480)]} 0 0
[junit4] 2> 1787443 INFO (qtp1572913099-87352) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[82 (1547133211359838208)]} 0 0
[junit4] 2> 1787445 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[83 (1547133211361935360)]} 0 0
[junit4] 2> 1787448 INFO (qtp1100876150-87386) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[84 (1547133211365081088)]} 0 0
[junit4] 2> 1787451 INFO (qtp1572913099-87352) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[85 (1547133211368226816)]} 0 0
[junit4] 2> 1787454 INFO (qtp549770350-87370) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[86 (1547133211371372544)]} 0 0
[junit4] 2> 1787456 INFO (qtp1100876150-87387) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[87 (1547133211373469696)]} 0 0
[junit4] 2> 1787459 INFO (qtp1572913099-87356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[88 (1547133211376615424)]} 0 0
[junit4] 2> 1787462 INFO (qtp549770350-87370) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[89 (1547133211378712576)]} 0 0
[junit4] 2> 1787466 INFO (qtp1100876150-87386) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[90 (1547133211382906880)]} 0 0
[junit4] 2> 1787469 INFO (qtp1572913099-87356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[91 (1547133211387101184)]} 0 0
[junit4] 2> 1787472 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[92 (1547133211390246912)]} 0 0
[junit4] 2> 1787476 INFO (qtp1100876150-87388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[93 (1547133211394441216)]} 0 0
[junit4] 2> 1787478 INFO (qtp1572913099-87356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[94 (1547133211396538368)]} 0 0
[junit4] 2> 1787480 INFO (qtp549770350-87371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[95 (1547133211398635520)]} 0 0
[junit4] 2> 1787483 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[96 (1547133211400732672)]} 0 0
[junit4] 2> 1787485 INFO (qtp1572913099-87358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[97 (1547133211403878400)]} 0 0
[junit4] 2> 1787488 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[98 (1547133211407024128)]} 0 0
[junit4] 2> 1787491 INFO (qtp1100876150-87392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[99 (1547133211410169856)]} 0 0
[junit4] 2> 1787497 INFO (qtp1572913099-87355) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1547133211416461312)]} 0 0
[junit4] 2> 1787502 INFO (qtp549770350-87368) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1547133211421704192)]} 0 0
[junit4] 2> 1787506 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1547133211424849920)]} 0 0
[junit4] 2> 1787509 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1547133211429044224)]} 0 0
[junit4] 2> 1787537 INFO (qtp549770350-87374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1547133211457355776)]} 0 0
[junit4] 2> 1787541 INFO (qtp1100876150-87387) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1547133211462598656)]} 0 0
[junit4] 2> 1787544 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1547133211465744384)]} 0 0
[junit4] 2> 1787548 INFO (qtp549770350-87370) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1547133211469938688)]} 0 0
[junit4] 2> 1787551 INFO (qtp1100876150-87389) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1547133211472035840)]} 0 0
[junit4] 2> 1787554 INFO (qtp1572913099-87352) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1547133211476230144)]} 0 0
[junit4] 2> 1787557 INFO (qtp549770350-87374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1547133211479375872)]} 0 0
[junit4] 2> 1787561 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1547133211483570176)]} 0 0
[junit4] 2> 1787564 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1547133211486715904)]} 0 0
[junit4] 2> 1787568 INFO (qtp549770350-87368) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1547133211490910208)]} 0 0
[junit4] 2> 1787572 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1547133211495104512)]} 0 0
[junit4] 2> 1787575 INFO (qtp1572913099-87358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1547133211498250240)]} 0 0
[junit4] 2> 1787578 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1547133211500347392)]} 0 0
[junit4] 2> 1787580 INFO (qtp1100876150-87388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1547133211503493120)]} 0 0
[junit4] 2> 1787585 INFO (qtp1572913099-87351) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1547133211508736000)]} 0 0
[junit4] 2> 1787588 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1547133211511881728)]} 0 0
[junit4] 2> 1787591 INFO (qtp1100876150-87387) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1547133211515027456)]} 0 0
[junit4] 2> 1787595 INFO (qtp1572913099-87356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1547133211518173184)]} 0 1
[junit4] 2> 1787598 INFO (qtp549770350-87369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1547133211522367488)]} 0 0
[junit4] 2> 1787603 INFO (qtp1100876150-87392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1547133211527610368)]} 0 0
[junit4] 2> 1787606 INFO (qtp1572913099-87355) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1547133211530756096)]} 0 0
[junit4] 2> 1787609 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1547133211533901824)]} 0 0
[junit4] 2> 1787613 INFO (qtp1100876150-87387) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1547133211538096128)]} 0 0
[junit4] 2> 1787620 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1547133211545436160)]} 0 0
[junit4] 2> 1787623 INFO (qtp549770350-87375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1547133211548581888)]} 0 0
[junit4] 2> 1787628 INFO (qtp1100876150-87391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1547133211552776192)]} 0 0
[junit4] 2> 1787632 INFO (qtp1572913099-87354) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1547133211558019072)]} 0 0
[junit4] 2> 1787635 INFO (qtp549770350-87368) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1547133211561164800)]} 0 0
[junit4] 2> 1787638 INFO (qtp1100876150-87389) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1547133211564310528)]} 0 0
[junit4] 2> 1787641 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1547133211567456256)]} 0 0
[junit4] 2> 1787645 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1547133211571650560)]} 0 0
[junit4] 2> 1787648 INFO (qtp1100876150-87389) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1547133211573747712)]} 0 0
[junit4] 2> 1787651 INFO (qtp1572913099-87355) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1547133211576893440)]} 0 0
[junit4] 2> 1787654 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1547133211580039168)]} 0 0
[junit4] 2> 1787658 INFO (qtp1100876150-87388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1547133211584233472)]} 0 0
[junit4] 2> 1787661 INFO (qtp1572913099-87351) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1547133211587379200)]} 0 0
[junit4] 2> 1787664 INFO (qtp549770350-87369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1547133211591573504)]} 0 0
[junit4] 2> 1787668 INFO (qtp1100876150-87391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1547133211595767808)]} 0 0
[junit4] 2> 1787675 INFO (qtp1572913099-87354) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1547133211603107840)]} 0 0
[junit4] 2> 1787679 INFO (qtp549770350-87368) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1547133211606253568)]} 0 1
[junit4] 2> 1787682 INFO (qtp1100876150-87389) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1547133211610447872)]} 0 0
[junit4] 2> 1787685 INFO (qtp1572913099-87352) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1547133211612545024)]} 0 0
[junit4] 2> 1787688 INFO (qtp549770350-87368) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1547133211616739328)]} 0 0
[junit4] 2> 1787692 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1547133211619885056)]} 0 0
[junit4] 2> 1787694 INFO (qtp1572913099-87355) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1547133211623030784)]} 0 0
[junit4] 2> 1787697 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1547133211626176512)]} 0 0
[junit4] 2> 1787700 INFO (qtp1100876150-87388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[150 (1547133211628273664)]} 0 0
[junit4] 2> 1787703 INFO (qtp1572913099-87355) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[151 (1547133211632467968)]} 0 0
[junit4] 2> 1787706 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[152 (1547133211635613696)]} 0 0
[junit4] 2> 1787708 INFO (qtp1100876150-87387) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[153 (1547133211637710848)]} 0 0
[junit4] 2> 1787711 INFO (qtp1572913099-87358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[154 (1547133211640856576)]} 0 0
[junit4] 2> 1787714 INFO (qtp549770350-87368) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[155 (1547133211644002304)]} 0 0
[junit4] 2> 1787717 INFO (qtp1100876150-87388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[156 (1547133211647148032)]} 0 0
[junit4] 2> 1787720 INFO (qtp1572913099-87352) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[157 (1547133211650293760)]} 0 0
[junit4] 2> 1787723 INFO (qtp549770350-87371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[158 (1547133211653439488)]} 0 0
[junit4] 2> 1787725 INFO (qtp1100876150-87391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[159 (1547133211655536640)]} 0 0
[junit4] 2> 1787729 INFO (qtp1572913099-87355) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[160 (1547133211659730944)]} 0 0
[junit4] 2> 1787732 INFO (qtp549770350-87374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[161 (1547133211661828096)]} 0 0
[junit4] 2> 1787735 INFO (qtp1100876150-87391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[162 (1547133211666022400)]} 0 0
[junit4] 2> 1787741 INFO (qtp1572913099-87356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[163 (1547133211672313856)]} 0 0
[junit4] 2> 1787744 INFO (qtp549770350-87371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[164 (1547133211675459584)]} 0 0
[junit4] 2> 1787746 INFO (qtp1100876150-87391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[165 (1547133211677556736)]} 0 0
[junit4] 2> 1787749 INFO (qtp1572913099-87358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[166 (1547133211680702464)]} 0 0
[junit4] 2> 1787754 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[167 (1547133211684896768)]} 0 0
[junit4] 2> 1787758 INFO (qtp1100876150-87391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[168 (1547133211689091072)]} 0 0
[junit4] 2> 1787760 INFO (qtp1572913099-87353) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[169 (1547133211692236800)]} 0 0
[junit4] 2> 1787764 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[170 (1547133211696431104)]} 0 0
[junit4] 2> 1787768 INFO (qtp1100876150-87392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[171 (1547133211700625408)]} 0 0
[junit4] 2> 1787771 INFO (qtp1572913099-87355) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[172 (1547133211703771136)]} 0 0
[junit4] 2> 1787775 INFO (qtp549770350-87373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[173 (1547133211707965440)]} 0 0
[junit4] 2> 1787778 INFO (qtp1100876150-87385) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[174 (1547133211711111168)]} 0 0
[junit4] 2> 1787781 INFO (qtp1572913099-87351) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[175 (1547133211714256896)]} 0 0
[junit4] 2> 1787785 INFO (qtp549770350-87371) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[176 (1547133211718451200)]} 0 0
[junit4] 2> 1787790 INFO (qtp1100876150-87388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[177 (1547133211723694080)]} 0 0
[junit4] 2> 1787793 INFO (qtp1572913099-87355) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[178 (1547133211726839808)]} 0 0
[junit4] 2> 1787800 INFO (qtp549770350-87374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[179 (1547133211733131264)]} 0 0
[junit4] 2> 1787802 INFO (qtp1100876150-87391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[180 (1547133211736276992)]} 0 0
[junit4] 2> 1787805 INFO (qtp1572913099-87358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[181 (1547133211739422720)]} 0 0
[junit4] 2> 1787807 INFO (qtp549770350-87369) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[182 (1547133211741519872)]} 0 0
[junit4] 2> 1787809 INFO (qtp1100876150-87392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[183 (1547133211743617024)]} 0 0
[junit4] 2> 1787813 INFO (qtp1572913099-87354) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[184 (1547133211747811328)]} 0 0
[junit4] 2> 1787815 INFO (qtp549770350-87370) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[185 (15471332117
[...truncated too long message...]
LBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=204600516
[junit4] 2> 127483 INFO (coreCloseExecutor-859-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@32f6de34
[junit4] 2> 127487 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6646f2f7{/solr,null,UNAVAILABLE}
[junit4] 2> 127488 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 127489 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@14b86ec1{/solr,null,AVAILABLE}
[junit4] 2> 127491 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.e.j.s.ServerConnector Started ServerConnector@14bd869{SSL,[ssl, http/1.1]}{127.0.0.1:42323}
[junit4] 2> 127491 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.e.j.s.Server Started @129746ms
[junit4] 2> 127491 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.TestLBHttpSolrClient_15E00891F2D26E64-001/instance-0-001/collection1/data, solrconfig=bad_solrconfig.xml, hostContext=/solr, hostPort=42323}
[junit4] 2> 127491 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 127491 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 127491 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 127491 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-03T02:30:34.674Z
[junit4] 2> 127491 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.TestLBHttpSolrClient_15E00891F2D26E64-001/instance-0-001/solr.xml
[junit4] 2> 127502 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.TestLBHttpSolrClient_15E00891F2D26E64-001/instance-0-001/.
[junit4] 2> 127502 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 127507 INFO (coreLoadExecutor-866-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 127514 INFO (coreLoadExecutor-866-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 127516 INFO (coreLoadExecutor-866-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 127517 INFO (coreLoadExecutor-866-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.TestLBHttpSolrClient_15E00891F2D26E64-001/instance-0-001/./collection1
[junit4] 2> 127517 INFO (coreLoadExecutor-866-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.TestLBHttpSolrClient_15E00891F2D26E64-001/instance-0-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.TestLBHttpSolrClient_15E00891F2D26E64-001/instance-0-001/./collection1/data/]
[junit4] 2> 127521 INFO (coreLoadExecutor-866-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 127522 INFO (coreLoadExecutor-866-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 127524 INFO (coreLoadExecutor-866-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@348c60dc[collection1] main]
[junit4] 2> 127524 INFO (coreLoadExecutor-866-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.TestLBHttpSolrClient_15E00891F2D26E64-001/instance-0-001/collection1/conf
[junit4] 2> 127524 INFO (coreLoadExecutor-866-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 127524 INFO (searcherExecutor-867-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@348c60dc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c10)))}
[junit4] 2> 128002 INFO (qtp1475998334-1785) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&distrib=false&sort=_docid_+asc&rows=0&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 129205 INFO (qtp1475998334-1792) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 2> 129207 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@14bd869{SSL,[ssl, http/1.1]}{127.0.0.1:42323}
[junit4] 2> 129207 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=244854304
[junit4] 2> 129207 INFO (coreCloseExecutor-871-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@74a98e57
[junit4] 2> 129212 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@14b86ec1{/solr,null,UNAVAILABLE}
[junit4] 2> 129215 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@216dd4e2{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 129215 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=114334899
[junit4] 2> 129216 INFO (coreCloseExecutor-873-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2a6828b7
[junit4] 2> 129219 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5d195413{/solr,null,UNAVAILABLE}
[junit4] 2> 129220 INFO (TEST-TestLBHttpSolrClient.testTwoServers-seed#[15E00891F2D26E64]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testTwoServers
[junit4] 2> 129221 INFO (SUITE-TestLBHttpSolrClient-seed#[15E00891F2D26E64]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.TestLBHttpSolrClient_15E00891F2D26E64-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {name=FSTOrd50, id=FSTOrd50}, docValues:{}, maxPointsInLeafNode=1304, maxMBSortInHeap=6.205093719661237, sim=ClassicSimilarity, locale=sr-CS, timezone=Libya
[junit4] 2> NOTE: Linux 4.4.0-36-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=12,threads=2,free=227746392,total=497549312
[junit4] 2> NOTE: All tests run in this JVM: [TestCollectionStateWatchers, GraphExpressionTest, TestUpdateRequestCodec, StreamExpressionToExpessionTest, TestSuggesterResponse, SolrQueryTest, SchemaTest, SolrDocumentTest, NoOpResponseParserTest, SolrExampleStreamingBinaryTest, TestSolrProperties, CommonParamsTest, ClientUtilsTest, QueryResponseTest, TestSpellCheckResponse, SolrParamTest, MergeIndexesEmbeddedTest, TestLBHttpSolrClient]
[junit4] Completed [85/85 (1!)] on J1 in 37.84s, 3 tests, 1 error <<< FAILURES!
[...truncated 1 lines...]
[junit4] JVM J1: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-solrj/test/temp/junit4-J1-20161003_022824_892.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
[junit4] Dumping heap to /home/jenkins/workspace/Lucene-Solr-master-Linux/heapdumps/java_pid21279.hprof ...
[junit4] Heap dump file created [505637880 bytes in 1.111 secs]
[junit4] <<< JVM J1: EOF ----
[...truncated 10381 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:763: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/build.xml:715: Some of the tests produced a heap dump, but did not fail. Maybe a suppressed OutOfMemoryError? Dumps created:
* java_pid21279.hprof
* java_pid25358.hprof
* java_pid25369.hprof
Total time: 62 minutes 28 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any