Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/17953/ Java: 64bit/jdk1.8.0_102 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed. FAILED: org.apache.solr.handler.component.DistributedFacetPivotLongTailTest.test Error Message: Error from server at https://127.0.0.1:32849//collection1: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 500 </title> </head> <body> <h2>HTTP ERROR: 500</h2> <p>Problem accessing /collection1/update. Reason: <pre> java.lang.OutOfMemoryError: GC overhead limit exceeded</pre></p> <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 9.3.8.v20160314</a><hr/> </body> </html> Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:32849//collection1: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 500 </title> </head> <body> <h2>HTTP ERROR: 500</h2> <p>Problem accessing /collection1/update. Reason: <pre> java.lang.OutOfMemoryError: GC overhead limit exceeded</pre></p> <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 9.3.8.v20160314</a><hr/> </body> </html> at __randomizedtesting.SeedInfo.seed([A97563B837A5A751:21215C629959CAA9]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:576) 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.commit(SolrClient.java:484) at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:463) at org.apache.solr.BaseDistributedSearchTestCase.commit(BaseDistributedSearchTestCase.java:549) at org.apache.solr.handler.component.DistributedFacetPivotLongTailTest.test(DistributedFacetPivotLongTailTest.java:102) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960) 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) Build Log: [...truncated 10984 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/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/init-core-data-001 [junit4] 2> 379548 INFO (SUITE-DistributedFacetPivotLongTailTest-seed#[A97563B837A5A751]-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> 379549 INFO (SUITE-DistributedFacetPivotLongTailTest-seed#[A97563B837A5A751]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 379694 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/control/cores/collection1 [junit4] 2> 379696 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 379697 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2bdb7ca7{/,null,AVAILABLE} [junit4] 2> 379699 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.ServerConnector Started ServerConnector@78402bd8{SSL,[ssl, http/1.1]}{127.0.0.1:38394} [junit4] 2> 379699 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.Server Started @383869ms [junit4] 2> 379700 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=38394, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/control/cores} [junit4] 2> 379700 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 379700 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 379700 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 379700 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-02T17:57:52.617Z [junit4] 2> 379700 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/control/solr.xml [junit4] 2> 379707 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/control/cores [junit4] 2> 379707 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 379708 INFO (coreLoadExecutor-4845-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> 379730 WARN (coreLoadExecutor-4845-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 379741 INFO (coreLoadExecutor-4845-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 379760 INFO (coreLoadExecutor-4845-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 379836 WARN (coreLoadExecutor-4845-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> 379837 INFO (coreLoadExecutor-4845-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 379843 INFO (coreLoadExecutor-4845-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> 379843 INFO (coreLoadExecutor-4845-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/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/control/cores/collection1/data/] [junit4] 2> 379843 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4765bbd0 [junit4] 2> 379844 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=42.5322265625, floorSegmentMB=0.361328125, forceMergeDeletesPctAllowed=11.492842664055091, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 379853 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 379853 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 379854 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 379854 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 379854 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=0.8544921875, floorSegmentMB=1.5048828125, forceMergeDeletesPctAllowed=18.22520062907555, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 379854 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4c5fde48[collection1] main] [junit4] 2> 379855 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 379880 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 379976 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 379976 INFO (searcherExecutor-4846-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 379976 INFO (searcherExecutor-4846-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 379976 INFO (searcherExecutor-4846-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 379976 INFO (searcherExecutor-4846-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 379977 INFO (searcherExecutor-4846-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 379977 INFO (searcherExecutor-4846-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 379977 INFO (searcherExecutor-4846-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 379977 INFO (searcherExecutor-4846-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 379978 INFO (searcherExecutor-4846-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 379978 INFO (searcherExecutor-4846-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4c5fde48[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 379979 INFO (coreLoadExecutor-4845-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547101612692996096 [junit4] 2> 380260 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard0/cores/collection1 [junit4] 2> 380262 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 380262 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@34f76acc{/,null,AVAILABLE} [junit4] 2> 380264 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.ServerConnector Started ServerConnector@134e8ea8{SSL,[ssl, http/1.1]}{127.0.0.1:32849} [junit4] 2> 380264 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.Server Started @384434ms [junit4] 2> 380264 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=32849, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard0/cores} [junit4] 2> 380265 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 380265 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 380265 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 380265 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-02T17:57:53.182Z [junit4] 2> 380265 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard0/solr.xml [junit4] 2> 380271 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard0/cores [junit4] 2> 380272 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 380272 INFO (coreLoadExecutor-4856-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> 380293 WARN (coreLoadExecutor-4856-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 380294 INFO (coreLoadExecutor-4856-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 380322 INFO (coreLoadExecutor-4856-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 380396 WARN (coreLoadExecutor-4856-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> 380398 INFO (coreLoadExecutor-4856-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 380405 INFO (coreLoadExecutor-4856-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> 380406 INFO (coreLoadExecutor-4856-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/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard0/cores/collection1/data/] [junit4] 2> 380406 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4765bbd0 [junit4] 2> 380406 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=42.5322265625, floorSegmentMB=0.361328125, forceMergeDeletesPctAllowed=11.492842664055091, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 380419 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 380419 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 380420 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 380420 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 380420 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=0.8544921875, floorSegmentMB=1.5048828125, forceMergeDeletesPctAllowed=18.22520062907555, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 380421 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@197ea9fa[collection1] main] [junit4] 2> 380421 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 380487 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 380788 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 380788 INFO (searcherExecutor-4857-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 380788 INFO (searcherExecutor-4857-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 380788 INFO (searcherExecutor-4857-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 380788 INFO (searcherExecutor-4857-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 380789 INFO (coreLoadExecutor-4856-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547101613542342656 [junit4] 2> 380789 INFO (searcherExecutor-4857-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 380789 INFO (searcherExecutor-4857-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 380789 INFO (searcherExecutor-4857-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 380789 INFO (searcherExecutor-4857-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 380790 INFO (searcherExecutor-4857-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 380791 INFO (searcherExecutor-4857-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@197ea9fa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 381323 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard1/cores/collection1 [junit4] 2> 381325 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 381326 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@40e3ce11{/,null,AVAILABLE} [junit4] 2> 381328 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7e44234b{SSL,[ssl, http/1.1]}{127.0.0.1:44265} [junit4] 2> 381328 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.Server Started @385498ms [junit4] 2> 381328 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=44265, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard1/cores} [junit4] 2> 381328 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 381329 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 381329 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 381329 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-02T17:57:54.246Z [junit4] 2> 381329 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard1/solr.xml [junit4] 2> 381335 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard1/cores [junit4] 2> 381335 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 381335 INFO (coreLoadExecutor-4867-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> 381354 WARN (coreLoadExecutor-4867-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 381356 INFO (coreLoadExecutor-4867-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 381374 INFO (coreLoadExecutor-4867-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 381460 WARN (coreLoadExecutor-4867-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> 381462 INFO (coreLoadExecutor-4867-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 381467 INFO (coreLoadExecutor-4867-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> 381468 INFO (coreLoadExecutor-4867-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/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard1/cores/collection1/data/] [junit4] 2> 381468 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4765bbd0 [junit4] 2> 381468 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=42.5322265625, floorSegmentMB=0.361328125, forceMergeDeletesPctAllowed=11.492842664055091, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 381482 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 381482 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 381482 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 381482 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 381483 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=0.8544921875, floorSegmentMB=1.5048828125, forceMergeDeletesPctAllowed=18.22520062907555, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 381484 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ea1610d[collection1] main] [junit4] 2> 381484 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 381549 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 381673 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 381673 INFO (searcherExecutor-4868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 381673 INFO (searcherExecutor-4868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 381673 INFO (searcherExecutor-4868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 381673 INFO (searcherExecutor-4868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 381673 INFO (searcherExecutor-4868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 381674 INFO (searcherExecutor-4868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 381674 INFO (coreLoadExecutor-4867-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547101614470332416 [junit4] 2> 381674 INFO (searcherExecutor-4868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 381674 INFO (searcherExecutor-4868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 381674 INFO (searcherExecutor-4868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 381675 INFO (searcherExecutor-4868-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2ea1610d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 381887 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard2/cores/collection1 [junit4] 2> 381889 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 381890 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4c880041{/,null,AVAILABLE} [junit4] 2> 381892 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.ServerConnector Started ServerConnector@80de3f3{SSL,[ssl, http/1.1]}{127.0.0.1:45196} [junit4] 2> 381892 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.Server Started @386062ms [junit4] 2> 381892 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=45196, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard2/cores} [junit4] 2> 381893 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0 [junit4] 2> 381893 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 381893 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 381893 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-02T17:57:54.810Z [junit4] 2> 381893 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard2/solr.xml [junit4] 2> 381900 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard2/cores [junit4] 2> 381900 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 381903 INFO (coreLoadExecutor-4878-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> 381927 WARN (coreLoadExecutor-4878-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 381929 INFO (coreLoadExecutor-4878-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 381946 INFO (coreLoadExecutor-4878-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 382033 WARN (coreLoadExecutor-4878-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> 382035 INFO (coreLoadExecutor-4878-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 382041 INFO (coreLoadExecutor-4878-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> 382042 INFO (coreLoadExecutor-4878-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/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001/tempDir-001/shard2/cores/collection1/data/] [junit4] 2> 382042 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4765bbd0 [junit4] 2> 382043 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=42.5322265625, floorSegmentMB=0.361328125, forceMergeDeletesPctAllowed=11.492842664055091, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 382057 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 382057 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 382058 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 382058 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 382059 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=0.8544921875, floorSegmentMB=1.5048828125, forceMergeDeletesPctAllowed=18.22520062907555, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 382059 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@402e9594[collection1] main] [junit4] 2> 382059 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 382090 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 382193 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 382193 INFO (searcherExecutor-4879-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 382193 INFO (searcherExecutor-4879-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 382193 INFO (searcherExecutor-4879-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 382193 INFO (searcherExecutor-4879-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 382194 INFO (searcherExecutor-4879-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 382194 INFO (coreLoadExecutor-4878-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547101615015591936 [junit4] 2> 382194 INFO (searcherExecutor-4879-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 382194 INFO (searcherExecutor-4879-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 382194 INFO (searcherExecutor-4879-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 382194 INFO (searcherExecutor-4879-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 382195 INFO (searcherExecutor-4879-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@402e9594[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 382404 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 382440 INFO (qtp521587976-80356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1547101615270395904)]} 0 3 [junit4] 2> 382472 INFO (qtp1551965839-80373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1547101615304998912)]} 0 2 [junit4] 2> 382513 INFO (qtp358522852-80390) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1547101615345893376)]} 0 3 [junit4] 2> 382519 INFO (qtp521587976-80363) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1547101615355330560)]} 0 0 [junit4] 2> 382523 INFO (qtp1551965839-80380) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1547101615360573440)]} 0 0 [junit4] 2> 382530 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1547101615366864896)]} 0 0 [junit4] 2> 382533 INFO (qtp521587976-80363) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1547101615371059200)]} 0 0 [junit4] 2> 382539 INFO (qtp1551965839-80379) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1547101615376302080)]} 0 0 [junit4] 2> 382543 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1547101615381544960)]} 0 0 [junit4] 2> 382549 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1547101615386787840)]} 0 0 [junit4] 2> 382553 INFO (qtp1551965839-80379) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1547101615392030720)]} 0 0 [junit4] 2> 382557 INFO (qtp358522852-80393) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1547101615396225024)]} 0 0 [junit4] 2> 382563 INFO (qtp521587976-80356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1547101615402516480)]} 0 0 [junit4] 2> 382567 INFO (qtp1551965839-80379) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1547101615406710784)]} 0 0 [junit4] 2> 382572 INFO (qtp358522852-80393) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1547101615411953664)]} 0 1 [junit4] 2> 382576 INFO (qtp521587976-80356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1547101615416147968)]} 0 0 [junit4] 2> 382580 INFO (qtp1551965839-80379) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1547101615420342272)]} 0 0 [junit4] 2> 382585 INFO (qtp358522852-80393) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[18 (1547101615425585152)]} 0 0 [junit4] 2> 382589 INFO (qtp521587976-80356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[19 (1547101615429779456)]} 0 0 [junit4] 2> 382594 INFO (qtp1551965839-80380) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[20 (1547101615435022336)]} 0 0 [junit4] 2> 382599 INFO (qtp358522852-80393) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[21 (1547101615440265216)]} 0 0 [junit4] 2> 382604 INFO (qtp521587976-80356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[22 (1547101615444459520)]} 0 0 [junit4] 2> 382609 INFO (qtp1551965839-80380) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[23 (1547101615449702400)]} 0 0 [junit4] 2> 382613 INFO (qtp358522852-80393) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[24 (1547101615454945280)]} 0 0 [junit4] 2> 382618 INFO (qtp521587976-80356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[25 (1547101615459139584)]} 0 0 [junit4] 2> 382622 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[26 (1547101615464382464)]} 0 0 [junit4] 2> 382625 INFO (qtp358522852-80393) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[27 (1547101615467528192)]} 0 0 [junit4] 2> 382629 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[28 (1547101615471722496)]} 0 0 [junit4] 2> 382633 INFO (qtp1551965839-80379) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[29 (1547101615475916800)]} 0 0 [junit4] 2> 382637 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[30 (1547101615480111104)]} 0 0 [junit4] 2> 382642 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[31 (1547101615484305408)]} 0 0 [junit4] 2> 382647 INFO (qtp1551965839-80379) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[32 (1547101615489548288)]} 0 0 [junit4] 2> 382651 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[33 (1547101615494791168)]} 0 0 [junit4] 2> 382656 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[34 (1547101615500034048)]} 0 1 [junit4] 2> 382660 INFO (qtp1551965839-80376) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[35 (1547101615503179776)]} 0 0 [junit4] 2> 382663 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[36 (1547101615506325504)]} 0 0 [junit4] 2> 382667 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[37 (1547101615510519808)]} 0 0 [junit4] 2> 382671 INFO (qtp1551965839-80373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[38 (1547101615515762688)]} 0 0 [junit4] 2> 382674 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[39 (1547101615518908416)]} 0 0 [junit4] 2> 382678 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[40 (1547101615522054144)]} 0 0 [junit4] 2> 382683 INFO (qtp1551965839-80380) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[41 (1547101615527297024)]} 0 0 [junit4] 2> 382686 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[42 (1547101615531491328)]} 0 0 [junit4] 2> 382691 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[43 (1547101615535685632)]} 0 0 [junit4] 2> 382702 INFO (qtp1551965839-80380) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[44 (1547101615547219968)]} 0 0 [junit4] 2> 382707 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[45 (1547101615552462848)]} 0 0 [junit4] 2> 382711 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[46 (1547101615557705728)]} 0 0 [junit4] 2> 382716 INFO (qtp1551965839-80380) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[47 (1547101615562948608)]} 0 0 [junit4] 2> 382720 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[48 (1547101615567142912)]} 0 0 [junit4] 2> 382724 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[49 (1547101615571337216)]} 0 0 [junit4] 2> 382729 INFO (qtp1551965839-80376) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[50 (1547101615576580096)]} 0 0 [junit4] 2> 382733 INFO (qtp358522852-80396) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[51 (1547101615580774400)]} 0 0 [junit4] 2> 382737 INFO (qtp521587976-80361) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[52 (1547101615584968704)]} 0 0 [junit4] 2> 382742 INFO (qtp1551965839-80373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[53 (1547101615589163008)]} 0 0 [junit4] 2> 382745 INFO (qtp358522852-80395) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[54 (1547101615593357312)]} 0 0 [junit4] 2> 382749 INFO (qtp521587976-80361) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[55 (1547101615597551616)]} 0 0 [junit4] 2> 382754 INFO (qtp1551965839-80376) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[56 (1547101615601745920)]} 0 0 [junit4] 2> 382757 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[57 (1547101615605940224)]} 0 0 [junit4] 2> 382761 INFO (qtp521587976-80358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[58 (1547101615610134528)]} 0 0 [junit4] 2> 382766 INFO (qtp1551965839-80380) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[59 (1547101615615377408)]} 0 0 [junit4] 2> 382770 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[60 (1547101615618523136)]} 0 0 [junit4] 2> 382773 INFO (qtp521587976-80356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[61 (1547101615622717440)]} 0 0 [junit4] 2> 382777 INFO (qtp1551965839-80375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[62 (1547101615626911744)]} 0 0 [junit4] 2> 382780 INFO (qtp358522852-80393) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[63 (1547101615630057472)]} 0 0 [junit4] 2> 382784 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[64 (1547101615634251776)]} 0 0 [junit4] 2> 382788 INFO (qtp1551965839-80376) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[65 (1547101615638446080)]} 0 0 [junit4] 2> 382792 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[66 (1547101615642640384)]} 0 0 [junit4] 2> 382797 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[67 (1547101615647883264)]} 0 0 [junit4] 2> 382801 INFO (qtp1551965839-80379) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[68 (1547101615651028992)]} 0 0 [junit4] 2> 382805 INFO (qtp358522852-80396) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[69 (1547101615656271872)]} 0 0 [junit4] 2> 382809 INFO (qtp521587976-80356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[70 (1547101615660466176)]} 0 0 [junit4] 2> 382814 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[71 (1547101615665709056)]} 0 0 [junit4] 2> 382817 INFO (qtp358522852-80395) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[72 (1547101615668854784)]} 0 0 [junit4] 2> 382821 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[73 (1547101615672000512)]} 0 0 [junit4] 2> 382825 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[74 (1547101615677243392)]} 0 0 [junit4] 2> 382828 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[75 (1547101615680389120)]} 0 0 [junit4] 2> 382832 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[76 (1547101615684583424)]} 0 0 [junit4] 2> 382836 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[77 (1547101615688777728)]} 0 0 [junit4] 2> 382840 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[78 (1547101615692972032)]} 0 0 [junit4] 2> 382844 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[79 (1547101615697166336)]} 0 0 [junit4] 2> 382849 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[80 (1547101615702409216)]} 0 0 [junit4] 2> 382853 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[81 (1547101615705554944)]} 0 0 [junit4] 2> 382856 INFO (qtp521587976-80363) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[82 (1547101615709749248)]} 0 0 [junit4] 2> 382861 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[83 (1547101615714992128)]} 0 0 [junit4] 2> 382865 INFO (qtp358522852-80395) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[84 (1547101615718137856)]} 0 0 [junit4] 2> 382869 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[85 (1547101615723380736)]} 0 0 [junit4] 2> 382874 INFO (qtp1551965839-80373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[86 (1547101615728623616)]} 0 0 [junit4] 2> 382878 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[87 (1547101615732817920)]} 0 0 [junit4] 2> 382883 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[88 (1547101615737012224)]} 0 0 [junit4] 2> 382888 INFO (qtp1551965839-80379) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[89 (1547101615742255104)]} 0 0 [junit4] 2> 382892 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[90 (1547101615746449408)]} 0 0 [junit4] 2> 382904 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[91 (1547101615759032320)]} 0 0 [junit4] 2> 382908 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[92 (1547101615764275200)]} 0 0 [junit4] 2> 382912 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[93 (1547101615768469504)]} 0 0 [junit4] 2> 382918 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[94 (1547101615773712384)]} 0 0 [junit4] 2> 382923 INFO (qtp1551965839-80376) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[95 (1547101615780003840)]} 0 0 [junit4] 2> 382927 INFO (qtp358522852-80395) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[96 (1547101615783149568)]} 0 0 [junit4] 2> 382931 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[97 (1547101615787343872)]} 0 0 [junit4] 2> 382935 INFO (qtp1551965839-80376) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[98 (1547101615792586752)]} 0 0 [junit4] 2> 382939 INFO (qtp358522852-80395) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[99 (1547101615796781056)]} 0 0 [junit4] 2> 382944 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1547101615800975360)]} 0 1 [junit4] 2> 382948 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1547101615806218240)]} 0 0 [junit4] 2> 382952 INFO (qtp358522852-80395) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1547101615809363968)]} 0 0 [junit4] 2> 382956 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1547101615814606848)]} 0 0 [junit4] 2> 382960 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1547101615818801152)]} 0 0 [junit4] 2> 382965 INFO (qtp358522852-80395) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1547101615822995456)]} 0 0 [junit4] 2> 382970 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1547101615828238336)]} 0 0 [junit4] 2> 382974 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1547101615832432640)]} 0 0 [junit4] 2> 382977 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1547101615836626944)]} 0 0 [junit4] 2> 382982 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1547101615840821248)]} 0 0 [junit4] 2> 382986 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1547101615846064128)]} 0 0 [junit4] 2> 382990 INFO (qtp358522852-80393) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1547101615850258432)]} 0 0 [junit4] 2> 382995 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1547101615855501312)]} 0 0 [junit4] 2> 382999 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1547101615859695616)]} 0 0 [junit4] 2> 383003 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1547101615862841344)]} 0 0 [junit4] 2> 383007 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1547101615868084224)]} 0 0 [junit4] 2> 383011 INFO (qtp1551965839-80376) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1547101615871229952)]} 0 0 [junit4] 2> 383014 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1547101615875424256)]} 0 0 [junit4] 2> 383018 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1547101615879618560)]} 0 0 [junit4] 2> 383021 INFO (qtp1551965839-80376) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1547101615882764288)]} 0 0 [junit4] 2> 383024 INFO (qtp358522852-80390) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1547101615885910016)]} 0 0 [junit4] 2> 383028 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1547101615890104320)]} 0 0 [junit4] 2> 383032 INFO (qtp1551965839-80376) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1547101615894298624)]} 0 0 [junit4] 2> 383035 INFO (qtp358522852-80390) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1547101615897444352)]} 0 0 [junit4] 2> 383040 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1547101615901638656)]} 0 1 [junit4] 2> 383044 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1547101615906881536)]} 0 0 [junit4] 2> 383047 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1547101615910027264)]} 0 0 [junit4] 2> 383052 INFO (qtp521587976-80361) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1547101615915270144)]} 0 0 [junit4] 2> 383057 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1547101615919464448)]} 0 0 [junit4] 2> 383061 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1547101615924707328)]} 0 0 [junit4] 2> 383068 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1547101615932047360)]} 0 0 [junit4] 2> 383072 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1547101615936241664)]} 0 0 [junit4] 2> 383077 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1547101615940435968)]} 0 0 [junit4] 2> 383081 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1547101615944630272)]} 0 0 [junit4] 2> 383085 INFO (qtp1551965839-80379) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1547101615948824576)]} 0 0 [junit4] 2> 383089 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1547101615954067456)]} 0 0 [junit4] 2> 383094 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1547101615958261760)]} 0 0 [junit4] 2> 383098 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1547101615963504640)]} 0 0 [junit4] 2> 383102 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1547101615967698944)]} 0 0 [junit4] 2> 383106 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1547101615971893248)]} 0 0 [junit4] 2> 383109 INFO (qtp1551965839-80375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1547101615975038976)]} 0 0 [junit4] 2> 383113 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1547101615979233280)]} 0 0 [junit4] 2> 383117 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1547101615982379008)]} 0 0 [junit4] 2> 383120 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1547101615985524736)]} 0 0 [junit4] 2> 383124 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1547101615989719040)]} 0 1 [junit4] 2> 383127 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1547101615992864768)]} 0 0 [junit4] 2> 383130 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1547101615997059072)]} 0 0 [junit4] 2> 383133 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1547101616000204800)]} 0 0 [junit4] 2> 383137 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1547101616004399104)]} 0 0 [junit4] 2> 383141 INFO (qtp1551965839-80376) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1547101616008593408)]} 0 0 [junit4] 2> 383145 INFO (qtp358522852-80395) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[150 (1547101616012787712)]} 0 0 [junit4] 2> 383150 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[151 (1547101616018030592)]} 0 0 [junit4] 2> 383154 INFO (qtp1551965839-80376) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[152 (1547101616022224896)]} 0 0 [junit4] 2> 383158 INFO (qtp358522852-80393) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[153 (1547101616025370624)]} 0 0 [junit4] 2> 383162 INFO (qtp521587976-80361) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[154 (1547101616030613504)]} 0 0 [junit4] 2> 383165 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[155 (1547101616033759232)]} 0 0 [junit4] 2> 383169 INFO (qtp358522852-80395) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[156 (1547101616037953536)]} 0 0 [junit4] 2> 383173 INFO (qtp521587976-80361) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[157 (1547101616042147840)]} 0 0 [junit4] 2> 383177 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[158 (1547101616046342144)]} 0 0 [junit4] 2> 383181 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[159 (1547101616050536448)]} 0 0 [junit4] 2> 383187 INFO (qtp521587976-80361) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[160 (1547101616056827904)]} 0 0 [junit4] 2> 383190 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[161 (1547101616059973632)]} 0 0 [junit4] 2> 383193 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[162 (1547101616063119360)]} 0 0 [junit4] 2> 383196 INFO (qtp521587976-80362) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[163 (1547101616066265088)]} 0 0 [junit4] 2> 383200 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[164 (1547101616069410816)]} 0 0 [junit4] 2> 383202 INFO (qtp358522852-80392) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[165 (1547101616072556544)]} 0 0 [junit4] 2> 383205 INFO (qtp521587976-80358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[166 (1547101616075702272)]} 0 0 [junit4] 2> 383208 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[167 (1547101616078848000)]} 0 0 [junit4] 2> 383211 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[168 (1547101616080945152)]} 0 0 [junit4] 2> 383214 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[169 (1547101616085139456)]} 0 0 [junit4] 2> 383217 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[170 (1547101616088285184)]} 0 0 [junit4] 2> 383220 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[171 (1547101616091430912)]} 0 0 [junit4] 2> 383223 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[172 (1547101616094576640)]} 0 0 [junit4] 2> 383226 INFO (qtp1551965839-80378) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[173 (1547101616097722368)]} 0 0 [junit4] 2> 383230 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[174 (1547101616100868096)]} 0 0 [junit4] 2> 383234 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[175 (1547101616106110976)]} 0 0 [junit4] 2> 383237 INFO (qtp1551965839-80379) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[176 (1547101616109256704)]} 0 0 [junit4] 2> 383240 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[177 (1547101616111353856)]} 0 0 [junit4] 2> 383242 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[178 (1547101616114499584)]} 0 0 [junit4] 2> 383247 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[179 (1547101616118693888)]} 0 0 [junit4] 2> 383250 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[180 (1547101616121839616)]} 0 0 [junit4] 2> 383252 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[181 (1547101616124985344)]} 0 0 [junit4] 2> 383255 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[182 (1547101616128131072)]} 0 0 [junit4] 2> 383258 INFO (qtp358522852-80391) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[183 (1547101616131276800)]} 0 0 [junit4] 2> 383263 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[184 (1547101616135471104)]} 0 0 [junit4] 2> 383266 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[185 (1547101616139665408)]} 0 0 [junit4] 2> 383270 INFO (qtp358522852-80393) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[186 (1547101616143859712)]} 0 0 [junit4] 2> 383273 INFO (qtp521587976-80356) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[187 (1547101616147005440)]} 0 0 [junit4] 2> 383277 INFO (qtp1551965839-80374) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[188 (1547101616151199744)]} 0 0 [junit4] 2> 383281 INFO (qtp358522852-80396) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[189 (1547101616155394048)]} 0 0 [junit4] 2> 383284 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[190 (1547101616158539776)]} 0 0 [junit4] 2> 383286 INFO (qtp1551965839-80375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[191 (1547101616160636928)]} 0 0 [junit4] 2> 383290 INFO (qtp358522852-80396) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[192 (1547101616163782656)]} 0 0 [junit4] 2> 383293 INFO (qtp521587976-80359) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[193 (1547101616167976960)]} 0 0 [junit4] 2> 383296 INFO (qtp1551965839-80375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[194 (1547101616171122688)]} 0 0 [junit4] 2> 383299 INFO (qtp358522852-80396) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[195 (1547101616173219840)]} 0 0 [junit4] 2> 383301 INFO (qtp521587976-80363) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[196 (1547101616176365568)]} 0 0 [junit4] 2> 383305 INFO (qtp1551965839-80373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[197 (1547101616180559872)]} 0 0 [junit4] 2> 383308 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[198 (1547101616183705600)]} 0 0 [junit4] 2> 383311 INFO (qtp521587976-80363) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[199 (1547101616186851328)]} 0 0 [junit4] 2> 383314 INFO (qtp1551965839-80373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[200 (1547101616189997056)]} 0 0 [junit4] 2> 383318 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[201 (1547101616193142784)]} 0 0 [junit4] 2> 383320 INFO (qtp521587976-80363) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[202 (1547101616196288512)]} 0 0 [junit4] 2> 383323 INFO (qtp1551965839-80373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[203 (1547101616199434240)]} 0 0 [junit4] 2> 383326 INFO (qtp358522852-80397) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[204 (1547101616202579968)]} 0 0 [junit4] 2> 383331 INFO (qtp521587976-80358) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[205 (1547101616207822848)]} 0 0 [junit4] 2> 383334 INFO (qtp1551965839-80373) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[206 (1547101616210968576)]} 0 0 [junit4] 2> 383337 INFO (qtp358522852-80396) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[207 (1547101616214114304)]} 0 0 [junit4] 2> 383342 INFO (qtp521587976-80363) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[208 (1547101616219357184)]} 0 0 [junit4] 2> 383345 INFO (qtp1551965839-80375) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[209 (1547101616222502912)]} 0 0 [junit4] 2> 383348 INFO (qtp358522852-80393) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[210 (1547101616225648640)]} 0 0 [junit4] 2> 383351 INFO (qtp521587976-80363) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[211 (1547101616228794368)]} 0 0 [junit4] 2> 383355 INFO (qtp1551965839-80379) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[212 (1547101616231940096)]} 0 0 [junit4] 2> 383359 INFO (qtp358522852-80390) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[213 (1547101616236134400)]} 0 0 [junit4] 2> 383362 INFO (qtp521587976-80363) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[214 (1547101616240328704)]} 0 0 [junit4] 2> 383366 INFO (qtp1551965839-80380) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[215 (1547101616244523008)]} 0 0 [junit4] 2> 383369 INFO (qtp358522852-80390) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[216 (1547101616247668736)]} 0 0 [junit4] 2> 383372 INFO (qtp521587976-80357) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[217 [...truncated too long message...] andler.java:581) [junit4] 2> 414393 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test [junit4] 2> 414993 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@78402bd8{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 414993 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1198660008 [junit4] 2> 415553 INFO (coreCloseExecutor-4884-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@9be462f [junit4] 2> 416740 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2bdb7ca7{/,null,UNAVAILABLE} [junit4] 2> 417585 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@134e8ea8{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 417585 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1981503559 [junit4] 2> 417585 INFO (coreCloseExecutor-4886-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@37ad7a0d [junit4] 2> 417594 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@34f76acc{/,null,UNAVAILABLE} [junit4] 2> 417891 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@7e44234b{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 417891 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=453966323 [junit4] 2> 417892 INFO (coreCloseExecutor-4888-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5ad685f0 [junit4] 2> 417892 INFO (coreCloseExecutor-4888-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 417892 INFO (coreCloseExecutor-4888-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1c762ec9 [junit4] 2> 417916 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@40e3ce11{/,null,UNAVAILABLE} [junit4] 2> 417935 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@80de3f3{SSL,[ssl, http/1.1]}{127.0.0.1:0} [junit4] 2> 417935 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1236258743 [junit4] 2> 417936 INFO (coreCloseExecutor-4890-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e9adfbb [junit4] 2> 417936 INFO (coreCloseExecutor-4890-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close. [junit4] 2> 417936 INFO (coreCloseExecutor-4890-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2e13685c [junit4] 2> 417957 INFO (TEST-DistributedFacetPivotLongTailTest.test-seed#[A97563B837A5A751]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4c880041{/,null,UNAVAILABLE} [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistributedFacetPivotLongTailTest -Dtests.method=test -Dtests.seed=A97563B837A5A751 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=mk-MK -Dtests.timezone=Asia/Kathmandu -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 38.6s J1 | DistributedFacetPivotLongTailTest.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:32849//collection1: Expected mime type application/octet-stream but got text/html. <html> [junit4] > <head> [junit4] > <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> [junit4] > <title>Error 500 </title> [junit4] > </head> [junit4] > <body> [junit4] > <h2>HTTP ERROR: 500</h2> [junit4] > <p>Problem accessing /collection1/update. Reason: [junit4] > <pre> java.lang.OutOfMemoryError: GC overhead limit exceeded</pre></p> [junit4] > <hr /><a href="http://eclipse.org/jetty";>Powered by Jetty:// 9.3.8.v20160314</a><hr/> [junit4] > </body> [junit4] > </html> [junit4] > at __randomizedtesting.SeedInfo.seed([A97563B837A5A751:21215C629959CAA9]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:576) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:261) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:250) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149) [junit4] > at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:484) [junit4] > at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:463) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase.commit(BaseDistributedSearchTestCase.java:549) [junit4] > at org.apache.solr.handler.component.DistributedFacetPivotLongTailTest.test(DistributedFacetPivotLongTailTest.java:102) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:985) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 418132 INFO (SUITE-DistributedFacetPivotLongTailTest-seed#[A97563B837A5A751]-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/J1/temp/solr.handler.component.DistributedFacetPivotLongTailTest_A97563B837A5A751-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {stat_i=Lucene50(blocksize=128), range_facet_l_dv=PostingsFormat(name=Memory doPackFST= false), _version_=Lucene50(blocksize=128), bar_s=PostingsFormat(name=Memory doPackFST= false), multiDefault=Lucene50(blocksize=128), intDefault=Lucene50(blocksize=128), foo_s=Lucene50(blocksize=128), id=PostingsFormat(name=Memory doPackFST= false), range_facet_i_dv=Lucene50(blocksize=128), range_facet_l=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1406, maxMBSortInHeap=7.1211591529833225, sim=ClassicSimilarity, locale=mk-MK, timezone=Asia/Kathmandu [junit4] 2> NOTE: Linux 4.4.0-36-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=12,threads=1,free=67560472,total=510132224 [junit4] 2> NOTE: All tests run in this JVM: [TestFieldCacheWithThreads, DeleteShardTest, RestartWhileUpdatingTest, TestFastLRUCache, TestSurroundQueryParser, ShardRoutingTest, LeaderFailoverAfterPartitionTest, TestConfigSetsAPIExclusivity, DistanceUnitsTest, DocValuesTest, BasicFunctionalityTest, ZkSolrClientTest, BlobRepositoryCloudTest, TestLockTree, DocValuesMissingTest, BadComponentTest, BJQParserTest, ConjunctionSolrSpellCheckerTest, BooleanFieldTest, TermVectorComponentTest, SolrIndexSplitterTest, TestEmbeddedSolrServerConstructors, CursorMarkTest, PKIAuthenticationIntegrationTest, TestFiltering, ZkCLITest, UpdateRequestProcessorFactoryTest, TestSolrDynamicMBean, TestLeaderInitiatedRecoveryThread, HdfsBasicDistributedZk2Test, TestSolrQueryResponse, SimplePostToolTest, TestFieldCache, HdfsChaosMonkeySafeLeaderTest, TestNonDefinedSimilarityFactory, JavabinLoaderTest, CreateCollectionCleanupTest, TestSmileRequest, TestStressLiveNodes, TestUpdate, TestDocBasedVersionConstraints, HdfsDirectoryTest, TestRuleBasedAuthorizationPlugin, SchemaVersionSpecificBehaviorTest, SpellCheckCollatorTest, ConcurrentDeleteAndCreateCollectionTest, BlockCacheTest, DistributedFacetPivotLongTailTest] [junit4] Completed [146/635 (1!)] on J1 in 38.61s, 1 test, 1 error <<< FAILURES! [...truncated 1568 lines...] [junit4] JVM J1: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/temp/junit4-J1-20161002_175128_713.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_pid30779.hprof ... [junit4] Heap dump file created [497511561 bytes in 1.318 secs] [junit4] <<< JVM J1: EOF ---- [...truncated 11050 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_pid30779.hprof Total time: 59 minutes 49 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
