You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/10/30 05:29:36 UTC
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_102) - Build #
549 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/549/
Java: 32bit/jdk1.8.0_102 -server -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.lucene.replicator.IndexReplicationClientTest.testConsistencyOnExceptions
Error Message:
Captured an uncaught exception in thread: Thread[id=22, name=ReplicationThread-index, state=RUNNABLE, group=TGRP-IndexReplicationClientTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=22, name=ReplicationThread-index, state=RUNNABLE, group=TGRP-IndexReplicationClientTest]
at __randomizedtesting.SeedInfo.seed([AC5E40A54CC19FE8:23D0A7055EAD6C17]:0)
Caused by: java.lang.AssertionError: handler failed too many times: -1
at __randomizedtesting.SeedInfo.seed([AC5E40A54CC19FE8]:0)
at org.apache.lucene.replicator.IndexReplicationClientTest$4.handleUpdateException(IndexReplicationClientTest.java:304)
at org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Expected to find shardAddress in the up shard info
Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info
at __randomizedtesting.SeedInfo.seed([E9786555F5908FBE:612C5A8F5B6CE246]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 8257 lines...]
[junit4] Suite: org.apache.lucene.replicator.IndexReplicationClientTest
[junit4] 2> ??? 29, 2016 11:59:44 ? com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
[junit4] 2> WARNING: Uncaught exception in thread: Thread[ReplicationThread-index,5,TGRP-IndexReplicationClientTest]
[junit4] 2> java.lang.AssertionError: handler failed too many times: -1
[junit4] 2> at __randomizedtesting.SeedInfo.seed([AC5E40A54CC19FE8]:0)
[junit4] 2> at org.apache.lucene.replicator.IndexReplicationClientTest$4.handleUpdateException(IndexReplicationClientTest.java:304)
[junit4] 2> at org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=IndexReplicationClientTest -Dtests.method=testConsistencyOnExceptions -Dtests.seed=AC5E40A54CC19FE8 -Dtests.slow=true -Dtests.locale=ar-BH -Dtests.timezone=America/Lower_Princes -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 2.75s J0 | IndexReplicationClientTest.testConsistencyOnExceptions <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=22, name=ReplicationThread-index, state=RUNNABLE, group=TGRP-IndexReplicationClientTest]
[junit4] > at __randomizedtesting.SeedInfo.seed([AC5E40A54CC19FE8:23D0A7055EAD6C17]:0)
[junit4] > Caused by: java.lang.AssertionError: handler failed too many times: -1
[junit4] > at __randomizedtesting.SeedInfo.seed([AC5E40A54CC19FE8]:0)
[junit4] > at org.apache.lucene.replicator.IndexReplicationClientTest$4.handleUpdateException(IndexReplicationClientTest.java:304)
[junit4] > at org.apache.lucene.replicator.ReplicationClient$ReplicationThread.run(ReplicationClient.java:77)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=225, maxMBSortInHeap=5.653197030203817, sim=ClassicSimilarity, locale=ar-BH, timezone=America/Lower_Princes
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_102 (32-bit)/cpus=3,threads=1,free=43891656,total=64880640
[junit4] 2> NOTE: All tests run in this JVM: [IndexAndTaxonomyReplicationClientTest, LocalReplicatorTest, IndexReplicationClientTest]
[junit4] Completed [7/9 (1!)] on J0 in 3.32s, 4 tests, 1 error <<< FAILURES!
[...truncated 3479 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001\init-core-data-001
[junit4] 2> 1433926 INFO (SUITE-TestDistributedSearch-seed#[E9786555F5908FBE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9061)
[junit4] 2> 1433926 INFO (SUITE-TestDistributedSearch-seed#[E9786555F5908FBE]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /i_t/
[junit4] 2> 1434617 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001\tempDir-001\control\cores\collection1
[junit4] 2> 1434621 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1434622 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@20bfa6{/i_t,null,AVAILABLE}
[junit4] 2> 1434639 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1f6510c{HTTP/1.1,[http/1.1]}{127.0.0.1:64859}
[junit4] 2> 1434639 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.Server Started @1439712ms
[junit4] 2> 1434639 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/i_t, hostPort=64859, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001\tempDir-001\control\cores}
[junit4] 2> 1434640 ERROR (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1434641 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0
[junit4] 2> 1434641 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1434641 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1434641 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-30T04:27:41.088Z
[junit4] 2> 1434642 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001\tempDir-001\control\solr.xml
[junit4] 2> 1434674 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1434679 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001\tempDir-001\control\cores
[junit4] 2> 1434679 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1434681 INFO (coreLoadExecutor-5647-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1434757 WARN (coreLoadExecutor-5647-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1434759 INFO (coreLoadExecutor-5647-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1434787 INFO (coreLoadExecutor-5647-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1434900 WARN (coreLoadExecutor-5647-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> 1434903 INFO (coreLoadExecutor-5647-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1434919 INFO (coreLoadExecutor-5647-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 1434919 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001\tempDir-001\control\cores\collection1\data\]
[junit4] 2> 1434919 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@16fbddb
[junit4] 2> 1434920 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=57.14453125, floorSegmentMB=1.3310546875, forceMergeDeletesPctAllowed=16.331991088775705, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.46111895852154494
[junit4] 2> 1434940 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1434940 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1434941 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1434941 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1434943 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=12.6669921875, floorSegmentMB=0.41796875, forceMergeDeletesPctAllowed=11.99324106908225, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1434943 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17cbfe7[collection1] main]
[junit4] 2> 1434943 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1434955 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1435006 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1435006 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1435006 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1435006 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1435006 INFO (coreLoadExecutor-5647-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549587354959740928
[junit4] 2> 1435006 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1435007 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1435007 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1435007 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1435008 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1435008 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1435008 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17cbfe7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1435829 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001\tempDir-001\shard0\cores\collection1
[junit4] 2> 1435833 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1435834 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@12bc7fe{/i_t,null,AVAILABLE}
[junit4] 2> 1435835 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@8331f8{HTTP/1.1,[http/1.1]}{127.0.0.1:64862}
[junit4] 2> 1435836 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.Server Started @1440909ms
[junit4] 2> 1435836 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/i_t, hostPort=64862, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001\tempDir-001\shard0\cores}
[junit4] 2> 1435836 ERROR (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1435837 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0
[junit4] 2> 1435837 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1435837 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1435837 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-30T04:27:42.284Z
[junit4] 2> 1435838 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001\tempDir-001\shard0\solr.xml
[junit4] 2> 1435856 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1435860 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001\tempDir-001\shard0\cores
[junit4] 2> 1435860 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1435862 INFO (coreLoadExecutor-5658-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1435934 WARN (coreLoadExecutor-5658-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1435936 INFO (coreLoadExecutor-5658-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1435972 INFO (coreLoadExecutor-5658-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1436085 WARN (coreLoadExecutor-5658-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> 1436087 INFO (coreLoadExecutor-5658-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1436110 INFO (coreLoadExecutor-5658-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 1436110 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001\tempDir-001\shard0\cores\collection1\data\]
[junit4] 2> 1436110 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@16fbddb
[junit4] 2> 1436111 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=57.14453125, floorSegmentMB=1.3310546875, forceMergeDeletesPctAllowed=16.331991088775705, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.46111895852154494
[junit4] 2> 1436134 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1436134 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1436135 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1436135 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1436136 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=12.6669921875, floorSegmentMB=0.41796875, forceMergeDeletesPctAllowed=11.99324106908225, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1436136 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e90a7c[collection1] main]
[junit4] 2> 1436136 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1436147 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1436198 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1436199 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1436199 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1436199 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1436199 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1436199 INFO (coreLoadExecutor-5658-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549587356210692096
[junit4] 2> 1436200 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1436200 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1436200 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1436200 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1436201 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1436204 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e90a7c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1436365 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1436372 INFO (qtp9000650-12221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1549587356388950016)} 0 3
[junit4] 2> 1436377 INFO (qtp6900351-12238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1549587356394192896)} 0 2
[junit4] 2> 1436380 INFO (qtp9000650-12222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[1 (1549587356399435776)]} 0 1
[junit4] 2> 1436396 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[1 (1549587356402581504)]} 0 14
[junit4] 2> 1436399 INFO (qtp9000650-12224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[2 (1549587356419358720)]} 0 0
[junit4] 2> 1436400 INFO (qtp6900351-12240) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[2 (1549587356421455872)]} 0 0
[junit4] 2> 1436402 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[3 (1549587356423553024)]} 0 0
[junit4] 2> 1436404 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[3 (1549587356425650176)]} 0 0
[junit4] 2> 1436405 INFO (qtp9000650-12226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[4 (1549587356426698752)]} 0 0
[junit4] 2> 1436408 INFO (qtp6900351-12242) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[4 (1549587356428795904)]} 0 0
[junit4] 2> 1436409 INFO (qtp9000650-12225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[5 (1549587356430893056)]} 0 0
[junit4] 2> 1436411 INFO (qtp6900351-12243) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[5 (1549587356432990208)]} 0 0
[junit4] 2> 1436413 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[6 (1549587356435087360)]} 0 0
[junit4] 2> 1436415 INFO (qtp6900351-12236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[6 (1549587356437184512)]} 0 0
[junit4] 2> 1436416 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[7 (1549587356438233088)]} 0 0
[junit4] 2> 1436419 INFO (qtp6900351-12236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[7 (1549587356441378816)]} 0 0
[junit4] 2> 1436422 INFO (qtp9000650-12222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[8 (1549587356444524544)]} 0 0
[junit4] 2> 1436425 INFO (qtp6900351-12236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[8 (1549587356447670272)]} 0 0
[junit4] 2> 1436427 INFO (qtp9000650-12224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[9 (1549587356449767424)]} 0 0
[junit4] 2> 1436429 INFO (qtp6900351-12240) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[9 (1549587356451864576)]} 0 0
[junit4] 2> 1436431 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1436431 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@97da98
[junit4] 2> 1436439 INFO (qtp9000650-12223) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1642c85[collection1] main]
[junit4] 2> 1436440 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1642c85[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C9)))}
[junit4] 2> 1436440 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1436440 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 9
[junit4] 2> 1436444 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1436444 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@832a74
[junit4] 2> 1436452 INFO (qtp6900351-12241) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a5931[collection1] main]
[junit4] 2> 1436453 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1436453 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6a5931[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C9)))}
[junit4] 2> 1436453 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 9
[junit4] 2> 1436458 INFO (qtp9000650-12226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[10 (1549587356479127552)]} 0 3
[junit4] 2> 1436463 INFO (qtp6900351-12242) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[10 (1549587356484370432)]} 0 2
[junit4] 2> 1436465 INFO (qtp9000650-12225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[11 (1549587356488564736)]} 0 0
[junit4] 2> 1436468 INFO (qtp6900351-12243) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[11 (1549587356491710464)]} 0 0
[junit4] 2> 1436470 INFO (qtp9000650-12221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[12 (1549587356494856192)]} 0 0
[junit4] 2> 1436472 INFO (qtp6900351-12238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[12 (1549587356496953344)]} 0 0
[junit4] 2> 1436475 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[13 (1549587356500099072)]} 0 0
[junit4] 2> 1436476 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[13 (1549587356501147648)]} 0 0
[junit4] 2> 1436480 INFO (qtp9000650-12222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[1001 (1549587356504293376)]} 0 1
[junit4] 2> 1436484 INFO (qtp6900351-12236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[1001 (1549587356508487680)]} 0 0
[junit4] 2> 1436485 INFO (qtp9000650-12224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[14 (1549587356510584832)]} 0 0
[junit4] 2> 1436487 INFO (qtp6900351-12240) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[14 (1549587356512681984)]} 0 0
[junit4] 2> 1436489 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[15 (1549587356514779136)]} 0 0
[junit4] 2> 1436492 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[15 (1549587356517924864)]} 0 0
[junit4] 2> 1436493 INFO (qtp9000650-12226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[16 (1549587356518973440)]} 0 0
[junit4] 2> 1436499 INFO (qtp6900351-12242) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[16 (1549587356524216320)]} 0 0
[junit4] 2> 1436501 INFO (qtp9000650-12225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[17 (1549587356526313472)]} 0 0
[junit4] 2> 1436503 INFO (qtp6900351-12243) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[17 (1549587356529459200)]} 0 0
[junit4] 2> 1436505 INFO (qtp9000650-12221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[100 (1549587356531556352)]} 0 0
[junit4] 2> 1436506 INFO (qtp6900351-12238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[100 (1549587356532604928)]} 0 0
[junit4] 2> 1436507 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[101 (1549587356533653504)]} 0 0
[junit4] 2> 1436508 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[101 (1549587356534702080)]} 0 0
[junit4] 2> 1436509 INFO (qtp9000650-12222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[102 (1549587356535750656)]} 0 0
[junit4] 2> 1436510 INFO (qtp6900351-12236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[102 (1549587356536799232)]} 0 0
[junit4] 2> 1436512 INFO (qtp9000650-12224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[103 (1549587356538896384)]} 0 0
[junit4] 2> 1436513 INFO (qtp6900351-12240) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[103 (1549587356539944960)]} 0 0
[junit4] 2> 1436515 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[104 (1549587356542042112)]} 0 0
[junit4] 2> 1436517 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[104 (1549587356544139264)]} 0 0
[junit4] 2> 1436518 INFO (qtp9000650-12226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[105 (1549587356545187840)]} 0 0
[junit4] 2> 1436520 INFO (qtp6900351-12242) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[105 (1549587356547284992)]} 0 0
[junit4] 2> 1436522 INFO (qtp9000650-12225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[106 (1549587356549382144)]} 0 0
[junit4] 2> 1436524 INFO (qtp6900351-12243) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[106 (1549587356551479296)]} 0 0
[junit4] 2> 1436525 INFO (qtp9000650-12221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[107 (1549587356552527872)]} 0 0
[junit4] 2> 1436526 INFO (qtp6900351-12238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[107 (1549587356553576448)]} 0 0
[junit4] 2> 1436528 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[108 (1549587356554625024)]} 0 0
[junit4] 2> 1436529 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[108 (1549587356556722176)]} 0 0
[junit4] 2> 1436531 INFO (qtp9000650-12222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[109 (1549587356558819328)]} 0 0
[junit4] 2> 1436533 INFO (qtp6900351-12236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[109 (1549587356560916480)]} 0 0
[junit4] 2> 1436534 INFO (qtp9000650-12224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[110 (1549587356561965056)]} 0 0
[junit4] 2> 1436536 INFO (qtp6900351-12240) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[110 (1549587356564062208)]} 0 0
[junit4] 2> 1436539 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[111 (1549587356567207936)]} 0 0
[junit4] 2> 1436541 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[111 (1549587356569305088)]} 0 0
[junit4] 2> 1436542 INFO (qtp9000650-12226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[112 (1549587356570353664)]} 0 0
[junit4] 2> 1436544 INFO (qtp6900351-12242) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[112 (1549587356572450816)]} 0 0
[junit4] 2> 1436545 INFO (qtp9000650-12225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[113 (1549587356573499392)]} 0 0
[junit4] 2> 1436547 INFO (qtp6900351-12243) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[113 (1549587356575596544)]} 0 0
[junit4] 2> 1436549 INFO (qtp9000650-12221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[114 (1549587356577693696)]} 0 0
[junit4] 2> 1436551 INFO (qtp6900351-12238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[114 (1549587356579790848)]} 0 0
[junit4] 2> 1436553 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[115 (1549587356581888000)]} 0 0
[junit4] 2> 1436554 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[115 (1549587356582936576)]} 0 0
[junit4] 2> 1436556 INFO (qtp9000650-12222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[116 (1549587356585033728)]} 0 0
[junit4] 2> 1436558 INFO (qtp6900351-12236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[116 (1549587356587130880)]} 0 0
[junit4] 2> 1436559 INFO (qtp9000650-12224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[117 (1549587356588179456)]} 0 0
[junit4] 2> 1436561 INFO (qtp6900351-12240) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[117 (1549587356590276608)]} 0 0
[junit4] 2> 1436563 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[118 (1549587356592373760)]} 0 0
[junit4] 2> 1436565 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[118 (1549587356594470912)]} 0 0
[junit4] 2> 1436566 INFO (qtp9000650-12226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[119 (1549587356595519488)]} 0 0
[junit4] 2> 1436568 INFO (qtp6900351-12242) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[119 (1549587356597616640)]} 0 0
[junit4] 2> 1436569 INFO (qtp9000650-12225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[120 (1549587356598665216)]} 0 0
[junit4] 2> 1436570 INFO (qtp6900351-12243) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[120 (1549587356599713792)]} 0 0
[junit4] 2> 1436571 INFO (qtp9000650-12221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[121 (1549587356600762368)]} 0 0
[junit4] 2> 1436572 INFO (qtp6900351-12238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[121 (1549587356601810944)]} 0 0
[junit4] 2> 1436573 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[122 (1549587356602859520)]} 0 0
[junit4] 2> 1436575 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[122 (1549587356604956672)]} 0 0
[junit4] 2> 1436578 INFO (qtp9000650-12222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[123 (1549587356608102400)]} 0 0
[junit4] 2> 1436580 INFO (qtp6900351-12236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[123 (1549587356610199552)]} 0 0
[junit4] 2> 1436581 INFO (qtp9000650-12224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[124 (1549587356611248128)]} 0 0
[junit4] 2> 1436582 INFO (qtp6900351-12240) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[124 (1549587356612296704)]} 0 0
[junit4] 2> 1436583 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[125 (1549587356613345280)]} 0 0
[junit4] 2> 1436584 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[125 (1549587356614393856)]} 0 0
[junit4] 2> 1436587 INFO (qtp9000650-12226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[126 (1549587356617539584)]} 0 0
[junit4] 2> 1436588 INFO (qtp6900351-12242) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[126 (1549587356618588160)]} 0 0
[junit4] 2> 1436589 INFO (qtp9000650-12225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[127 (1549587356619636736)]} 0 0
[junit4] 2> 1436591 INFO (qtp6900351-12243) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[127 (1549587356621733888)]} 0 0
[junit4] 2> 1436593 INFO (qtp9000650-12221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[128 (1549587356623831040)]} 0 0
[junit4] 2> 1436594 INFO (qtp6900351-12238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[128 (1549587356624879616)]} 0 0
[junit4] 2> 1436596 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[129 (1549587356626976768)]} 0 0
[junit4] 2> 1436597 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[129 (1549587356628025344)]} 0 0
[junit4] 2> 1436600 INFO (qtp9000650-12222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[130 (1549587356631171072)]} 0 0
[junit4] 2> 1436603 INFO (qtp6900351-12236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[130 (1549587356633268224)]} 0 1
[junit4] 2> 1436604 INFO (qtp9000650-12224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[131 (1549587356635365376)]} 0 0
[junit4] 2> 1436607 INFO (qtp6900351-12240) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[131 (1549587356638511104)]} 0 0
[junit4] 2> 1436609 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[132 (1549587356640608256)]} 0 0
[junit4] 2> 1436611 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[132 (1549587356642705408)]} 0 0
[junit4] 2> 1436614 INFO (qtp9000650-12226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[133 (1549587356645851136)]} 0 0
[junit4] 2> 1436616 INFO (qtp6900351-12242) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[133 (1549587356647948288)]} 0 0
[junit4] 2> 1436618 INFO (qtp9000650-12225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[134 (1549587356650045440)]} 0 0
[junit4] 2> 1436620 INFO (qtp6900351-12243) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[134 (1549587356652142592)]} 0 0
[junit4] 2> 1436621 INFO (qtp9000650-12221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[135 (1549587356653191168)]} 0 0
[junit4] 2> 1436623 INFO (qtp6900351-12238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[135 (1549587356655288320)]} 0 0
[junit4] 2> 1436626 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[136 (1549587356658434048)]} 0 0
[junit4] 2> 1436629 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[136 (1549587356661579776)]} 0 0
[junit4] 2> 1436631 INFO (qtp9000650-12222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[137 (1549587356663676928)]} 0 0
[junit4] 2> 1436632 INFO (qtp6900351-12236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[137 (1549587356664725504)]} 0 0
[junit4] 2> 1436633 INFO (qtp9000650-12224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[138 (1549587356665774080)]} 0 0
[junit4] 2> 1436637 INFO (qtp6900351-12240) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[138 (1549587356669968384)]} 0 0
[junit4] 2> 1436639 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[139 (1549587356672065536)]} 0 0
[junit4] 2> 1436642 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[139 (1549587356675211264)]} 0 0
[junit4] 2> 1436643 INFO (qtp9000650-12226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[140 (1549587356676259840)]} 0 0
[junit4] 2> 1436645 INFO (qtp6900351-12242) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[140 (1549587356678356992)]} 0 0
[junit4] 2> 1436647 INFO (qtp9000650-12225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[141 (1549587356680454144)]} 0 0
[junit4] 2> 1436648 INFO (qtp6900351-12243) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[141 (1549587356681502720)]} 0 0
[junit4] 2> 1436651 INFO (qtp9000650-12221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[142 (1549587356684648448)]} 0 0
[junit4] 2> 1436653 INFO (qtp6900351-12238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[142 (1549587356686745600)]} 0 0
[junit4] 2> 1436655 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[143 (1549587356688842752)]} 0 0
[junit4] 2> 1436657 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[143 (1549587356690939904)]} 0 0
[junit4] 2> 1436659 INFO (qtp9000650-12222) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[144 (1549587356693037056)]} 0 0
[junit4] 2> 1436662 INFO (qtp6900351-12236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[144 (1549587356696182784)]} 0 0
[junit4] 2> 1436664 INFO (qtp9000650-12224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[145 (1549587356698279936)]} 0 0
[junit4] 2> 1436666 INFO (qtp6900351-12240) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[145 (1549587356700377088)]} 0 0
[junit4] 2> 1436668 INFO (qtp9000650-12223) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[146 (1549587356702474240)]} 0 0
[junit4] 2> 1436670 INFO (qtp6900351-12241) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[146 (1549587356704571392)]} 0 0
[junit4] 2> 1436671 INFO (qtp9000650-12226) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[147 (1549587356705619968)]} 0 0
[junit4] 2> 1436673 INFO (qtp6900351-12242) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[147 (1549587356707717120)]} 0 0
[junit4] 2> 1436675 INFO (qtp9000650-12225) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[148 (1549587356709814272)]} 0 0
[junit4] 2> 1436677 INFO (qtp6900351-12243) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[148 (1549587356711911424)]} 0 0
[junit4] 2> 1436679 INFO (qtp9000650-12221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[149 (1549587356714008576)]} 0 0
[junit4] 2> 1436680 INFO (qtp6900351-12238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={wt=javabin&version=2}{add=[149 (1549587356715057152)]} 0 0
[junit4] 2> 1436682 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1436682 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@97da98
[junit4] 2> 1436709 INFO (qtp9000650-12219) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b79b8[collection1] main]
[junit4] 2> 1436710 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1436710 INFO (searcherExecutor-5648-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4b79b8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C9) Uninverting(_1(6.3.0):C59)))}
[junit4] 2> 1436712 INFO (qtp9000650-12219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 30
[junit4] 2> 1436714 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1436714 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@832a74
[junit4] 2> 1436739 INFO (qtp6900351-12239) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a2ab58[collection1] main]
[junit4] 2> 1436739 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1436740 INFO (searcherExecutor-5659-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a2ab58[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C9) Uninverting(_1(6.3.0):C59)))}
[junit4] 2> 1436740 INFO (qtp6900351-12239) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/i_t path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 26
[junit4] 2> 1436743 INFO (qtp9000650-12222) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1436743 INFO (qtp9000650-12222) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1436745 INFO (qtp9000650-12224) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 1436745 INFO (qtp9000650-12224) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 1436747 INFO (qtp9000650-12223) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436755 INFO (qtp6900351-12241) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&rows=10&version=2&q=*:*&NOW=1477801663196&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 1436756 INFO (qtp6900351-12242) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663196&ids=110,144,101,14,3,114,5,17,138,127&isShard=true&shard.url=[ff01::083]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436761 INFO (qtp6900351-12236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=[ff01::083]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=11
[junit4] 2> 1436764 INFO (qtp9000650-12226) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436770 INFO (qtp6900351-12238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:64862/i_t/collection1&rows=10&version=2&q=*:*&NOW=1477801663214&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436772 INFO (qtp6900351-12239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663214&ids=11,122,145,2,103,105,8,129,140,141&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436779 INFO (qtp6900351-12243) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=12
[junit4] 2> 1436781 INFO (qtp9000650-12225) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436787 INFO (qtp6900351-12241) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:64862/i_t/collection1&rows=10&version=2&q=*:*&NOW=1477801663231&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436791 INFO (qtp6900351-12242) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663231&ids=11,133,102,4,137,116,127,128,117,106&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1436795 INFO (qtp6900351-12240) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=10
[junit4] 2> 1436796 INFO (qtp9000650-12221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436813 INFO (qtp6900351-12238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::083]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&rows=10&version=2&q=*:*&NOW=1477801663246&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436816 INFO (qtp6900351-12239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663246&ids=123,101,135,148,16,8,107,141,130,10&isShard=true&shard.url=[ff01::083]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436819 INFO (qtp6900351-12236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=[ff01::083]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=20
[junit4] 2> 1436821 INFO (qtp9000650-12219) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436825 INFO (qtp6900351-12241) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&rows=10&version=2&q=*:*&NOW=1477801663270&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436828 INFO (qtp6900351-12242) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663270&ids=113,124,102,125,114,1001,107,119,140,141&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436831 INFO (qtp6900351-12243) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 1436834 INFO (qtp9000650-12222) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436841 INFO (qtp6900351-12238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::083]:33332/i_t|[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&rows=10&version=2&q=*:*&NOW=1477801663284&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436844 INFO (qtp6900351-12239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663284&ids=121,132,111,12,101,13,138,117,8,142&isShard=true&shard.url=[ff01::083]:33332/i_t|[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436845 INFO (qtp6900351-12240) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=[ff01::083]:33332/i_t|[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 1436847 INFO (qtp9000650-12224) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436851 INFO (qtp6900351-12241) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&rows=10&version=2&q=*:*&NOW=1477801663296&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436853 INFO (qtp6900351-12242) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663296&ids=1,12,111,124,3,146,17,117,118,119&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436854 INFO (qtp6900351-12236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 1436856 INFO (qtp9000650-12223) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436861 INFO (qtp6900351-12238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=[ff01::083]:33332/i_t|[ff01::114]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&rows=10&version=2&q=*:*&NOW=1477801663306&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436865 INFO (qtp6900351-12239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663306&ids=134,102,14,103,5,6,1001,140,130,142&isShard=true&shard.url=[ff01::083]:33332/i_t|[ff01::114]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436866 INFO (qtp6900351-12243) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=[ff01::083]:33332/i_t|[ff01::114]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 1436868 INFO (qtp9000650-12226) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436872 INFO (qtp6900351-12241) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&rows=10&version=2&q=*:*&NOW=1477801663318&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436875 INFO (qtp6900351-12242) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663318&ids=121,145,3,146,14,102,148,105,128,130&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436876 INFO (qtp6900351-12240) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1436878 INFO (qtp9000650-12225) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436882 INFO (qtp6900351-12238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::213]:33332/i_t|[ff01::083]:33332/i_t&rows=10&version=2&q=*:*&NOW=1477801663327&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436884 INFO (qtp6900351-12239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663327&ids=144,113,135,137,138,106,118,9,109,10&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::213]:33332/i_t|[ff01::083]:33332/i_t&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436885 INFO (qtp6900351-12236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1|[ff01::213]:33332/i_t|[ff01::083]:33332/i_t&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 1436887 INFO (qtp9000650-12221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436891 INFO (qtp6900351-12241) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=[ff01::083]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&rows=10&version=2&q=*:*&NOW=1477801663337&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436894 INFO (qtp6900351-12242) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663337&ids=132,13,146,113,16,137,106,119,141,142&isShard=true&shard.url=[ff01::083]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436895 INFO (qtp6900351-12243) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=[ff01::083]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1436896 INFO (qtp9000650-12219) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436914 INFO (qtp6900351-12238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1|[ff01::114]:33332/i_t|[ff01::083]:33332/i_t&rows=10&version=2&q=*:*&NOW=1477801663346&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436915 INFO (qtp6900351-12239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663346&ids=110,121,133,123,2,14,114,103,117,131&isShard=true&shard.url=[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1|[ff01::114]:33332/i_t|[ff01::083]:33332/i_t&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436916 INFO (qtp6900351-12240) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1|[ff01::114]:33332/i_t|[ff01::083]:33332/i_t&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=17
[junit4] 2> 1436918 INFO (qtp9000650-12222) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436924 INFO (qtp6900351-12241) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:64862/i_t/collection1&rows=10&version=2&q=*:*&NOW=1477801663368&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436926 INFO (qtp6900351-12242) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663368&ids=133,2,135,114,137,138,129,9,141,142&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436927 INFO (qtp6900351-12236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1436929 INFO (qtp9000650-12224) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436933 INFO (qtp6900351-12238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t|[ff01::213]:33332/i_t|[ff01::114]:33332/i_t&rows=10&version=2&q=*:*&NOW=1477801663378&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436936 INFO (qtp6900351-12239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663378&ids=110,132,144,134,13,125,1001,119,109,130&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t|[ff01::213]:33332/i_t|[ff01::114]:33332/i_t&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436937 INFO (qtp6900351-12243) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t|[ff01::213]:33332/i_t|[ff01::114]:33332/i_t&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1436939 INFO (qtp9000650-12223) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436944 INFO (qtp6900351-12241) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::213]:33332/i_t&rows=10&version=2&q=*:*&NOW=1477801663389&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436947 INFO (qtp6900351-12242) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663389&ids=134,13,146,104,16,126,105,149,8,140&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::213]:33332/i_t&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436948 INFO (qtp6900351-12240) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1|[ff01::213]:33332/i_t&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 1436949 INFO (qtp9000650-12226) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436953 INFO (qtp6900351-12238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=[ff01::114]:33332/i_t|http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&rows=10&version=2&q=*:*&NOW=1477801663398&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436955 INFO (qtp6900351-12239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663398&ids=1,122,12,100,6,7,128,119,141,131&isShard=true&shard.url=[ff01::114]:33332/i_t|http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436956 INFO (qtp6900351-12236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=[ff01::114]:33332/i_t|http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 1436957 INFO (qtp9000650-12225) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436962 INFO (qtp6900351-12241) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&rows=10&version=2&q=*:*&NOW=1477801663407&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436965 INFO (qtp6900351-12242) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663407&ids=11,100,101,4,125,137,138,139,106,9&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436966 INFO (qtp6900351-12243) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1436967 INFO (qtp9000650-12221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436972 INFO (qtp6900351-12238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&rows=10&version=2&q=*:*&NOW=1477801663418&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436974 INFO (qtp6900351-12239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663418&ids=121,12,145,15,104,117,107,109,120,142&isShard=true&shard.url=[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436975 INFO (qtp6900351-12240) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 1436978 INFO (qtp9000650-12219) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436981 INFO (qtp6900351-12241) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=http://127.0.0.1:64862/i_t/collection1&rows=10&version=2&q=*:*&NOW=1477801663427&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436984 INFO (qtp6900351-12242) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663427&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1436985 INFO (qtp6900351-12236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 1436989 INFO (qtp9000650-12222) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1436996 INFO (qtp6900351-12238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&rows=10&version=2&q=*:*&NOW=1477801663439&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1436999 INFO (qtp6900351-12239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663439&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1437000 INFO (qtp6900351-12243) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=7
[junit4] 2> 1437001 INFO (qtp9000650-12224) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&sort=a_i1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1437005 INFO (qtp6900351-12241) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc&shard.url=[ff01::114]:33332/i_t|[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&rows=10&version=2&q=*:*&NOW=1477801663450&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1437009 INFO (qtp6900351-12242) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1477801663450&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::114]:33332/i_t|[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1437010 INFO (qtp6900351-12240) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&shards=[ff01::114]:33332/i_t|[ff01::213]:33332/i_t|http://127.0.0.1:64862/i_t/collection1|[ff01::083]:33332/i_t&sort=a_i1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 1437012 INFO (qtp9000650-12223) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=false&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 1437015 INFO (qtp6900351-12238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=http://127.0.0.1:64862/i_t/collection1&rows=10&version=2&q=*:*&NOW=1477801663461&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 1437017 INFO (qtp6900351-12239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/i_t path=/select params={q=*:*&distrib=fal
[...truncated too long message...]
.tolerant=true&timeAllowed=1&wt=javabin&group=true} status=0 QTime=36
[junit4] 2> 1506443 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 1506444 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@b1cda5{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1506444 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=5550603
[junit4] 2> 1506446 INFO (coreCloseExecutor-5701-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@16306b4
[junit4] 2> 1506509 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1eee2a{/i_t,null,UNAVAILABLE}
[junit4] 2> 1506511 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@6a8ff{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1506511 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=8841722
[junit4] 2> 1506512 INFO (coreCloseExecutor-5703-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@183e350
[junit4] 2> 1506544 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1cacdf2{/i_t,null,UNAVAILABLE}
[junit4] 2> 1506546 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@18ac965{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1506546 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=7799806
[junit4] 2> 1506546 INFO (coreCloseExecutor-5705-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@12b6abc
[junit4] 2> 1506588 INFO (TEST-TestDistributedSearch.test-seed#[E9786555F5908FBE]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@21a661{/i_t,null,UNAVAILABLE}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=E9786555F5908FBE -Dtests.slow=true -Dtests.locale=sr-CS -Dtests.timezone=Europe/Zagreb -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 72.7s J1 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected to find shardAddress in the up shard info
[junit4] > at __randomizedtesting.SeedInfo.seed([E9786555F5908FBE:612C5A8F5B6CE246]:0)
[junit4] > at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1506592 INFO (SUITE-TestDistributedSearch-seed#[E9786555F5908FBE]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_E9786555F5908FBE-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {other_tl1=PostingsFormat(name=Direct), range_facet_l_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval), SubjectTerms_mfacet=PostingsFormat(name=Direct), n_l1=PostingsFormat(name=Direct), a_n_tdt=PostingsFormat(name=LuceneVarGapDocFreqInterval), multiDefault=FSTOrd50, intDefault=PostingsFormat(name=Direct), n_td1=FSTOrd50, n_d1=PostingsFormat(name=Direct), n_f1=FSTOrd50, range_facet_l=PostingsFormat(name=Direct), n_tl1=FSTOrd50, oddField_s=PostingsFormat(name=Memory doPackFST= true), n_tf1=PostingsFormat(name=Direct), lowerfilt1and2=FSTOrd50, a_t=PostingsFormat(name=Direct), lowerfilt=FSTOrd50, id=PostingsFormat(name=LuceneVarGapDocFreqInterval), text=PostingsFormat(name=Memory doPackFST= true), timestamp=PostingsFormat(name=Direct), severity=PostingsFormat(name=Memory doPackFST= true), foo_sev_enum=PostingsFormat(name=LuceneVarGapDocFreqInterval), n_dt1=FSTOrd50, a_i1=PostingsFormat(name=Direct), b_n_tdt=PostingsFormat(name=Direct), n_ti1=PostingsFormat(name=LuceneVarGapDocFreqInterval), rnd_b=PostingsFormat(name=LuceneVarGapDocFreqInterval), _version_=PostingsFormat(name=Direct), foo_b=PostingsFormat(name=Memory doPackFST= true), n_tdt1=PostingsFormat(name=Direct), foo_d=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_i_dv=PostingsFormat(name=Direct), foo_f=PostingsFormat(name=Memory doPackFST= true)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), foo_sev_enum=DocValuesFormat(name=Lucene54), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1318, maxMBSortInHeap=7.8016825804392, sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=sr-CS, timezone=Europe/Zagreb
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_102 (32-bit)/cpus=3,threads=1,free=69571504,total=277667840
[junit4] 2> NOTE: All tests run in this JVM: [HdfsNNFailoverTest, SSLMigrationTest, DistributedVersionInfoTest, CollectionTooManyReplicasTest, OverseerRolesTest, TestSolrCloudWithDelegationTokens, TestRandomRequestDistribution, CoreAdminHandlerTest, CurrencyFieldOpenExchangeTest, TestJoin, RAMDirectoryFactoryTest, FacetPivotSmallTest, TestRequestStatusCollectionAPI, TestRealTimeGet, UUIDFieldTest, TestSolrQueryParser, WordBreakSolrSpellCheckerTest, FullSolrCloudDistribCmdsTest, CloneFieldUpdateProcessorFactoryTest, FileBasedSpellCheckerTest, ExplicitHLLTest, TestCrossCoreJoin, TestSolrConfigHandler, TestDistribIDF, CircularListTest, IndexSchemaTest, TestValueSourceCache, MinimalSchemaTest, TestManagedSchemaThreadSafety, CoreAdminCreateDiscoverTest, CollectionReloadTest, TestSchemaSimilarityResource, CdcrBootstrapTest, TestWriterPerf, CacheHeaderTest, TermVectorComponentDistributedTest, BadIndexSchemaTest, TestSubQueryTransformerDistrib, TestFilteredDocIdSet, TestSolrCoreSnapshots, CachingDirectoryFactoryTest, TestAnalyzeInfixSuggestions, HLLSerializationTest, SignatureUpdateProcessorFactoryTest, TestBulkSchemaAPI, DistributedTermsComponentTest, MBeansHandlerTest, TestJsonRequest, TestDistributedStatsComponentCardinality, AsyncCallRequestStatusResponseTest, DirectUpdateHandlerOptimizeTest, TestLMDirichletSimilarityFactory, TestAddFieldRealTimeGet, TestQueryWrapperFilter, BasicZkTest, PreAnalyzedUpdateProcessorTest, BlockDirectoryTest, TestImplicitCoreProperties, TlogReplayBufferedWhileIndexingTest, TestConfigSetImmutable, SolrCmdDistributorTest, QueryParsingTest, ResponseHeaderTest, TestLegacyFieldCache, TestUseDocValuesAsStored, TestQueryUtils, HdfsRecoveryZkTest, TestRangeQuery, FieldMutatingUpdateProcessorTest, TestCloudDeleteByQuery, BigEndianAscendingWordSerializerTest, TestBlobHandler, DistributedExpandComponentTest, TestConfigSetsAPIExclusivity, TestDynamicFieldCollectionResource, ChangedSchemaMergeTest, CreateCollectionCleanupTest, HdfsTlogReplayBufferedWhileIndexingTest, TestExclusionRuleCollectionAccess, NotRequiredUniqueKeyTest, DateMathParserTest, TestJmxMonitoredMap, TestCloudRecovery, TestSortingResponseWriter, TestRawTransformer, TestIndexSearcher, ScriptEngineTest, LeaderElectionIntegrationTest, LeaderInitiatedRecoveryOnCommitTest, TestNumericTerms64, TestXmlQParser, ClusterStateTest, TestGraphMLResponseWriter, SolrCLIZkUtilsTest, TestCodecSupport, OutOfBoxZkACLAndCredentialsProvidersTest, TestUtils, TestLeaderElectionWithEmptyReplica, TestReload, HdfsDirectoryFactoryTest, ZkSolrClientTest, TestLeaderInitiatedRecoveryThread, TestFoldingMultitermQuery, TestIndexingPerformance, TestFieldCollectionResource, TestSearchPerf, BasicAuthIntegrationTest, TestLMJelinekMercerSimilarityFactory, NoCacheHeaderTest, TestAtomicUpdateErrorCases, DebugComponentTest, SpellCheckComponentTest, TestExceedMaxTermLength, ConvertedLegacyTest, TestFieldCacheVsDocValues, LukeRequestHandlerTest, TestQuerySenderNoQuery, ShowFileRequestHandlerTest, TestSerializedLuceneMatchVersion, TestHdfsBackupRestoreCore, TestSolrQueryParserResource, SearchHandlerTest, SortByFunctionTest, FieldAnalysisRequestHandlerTest, TestSolrCLIRunExample, UpdateParamsTest, SolrXmlInZkTest, VersionInfoTest, SimpleMLTQParserTest, TestHashQParserPlugin, CopyFieldTest, TestCryptoKeys, TestCustomStream, LeaderFailoverAfterPartitionTest, TestManagedStopFilterFactory, RuleEngineTest, TestSortByMinMaxFunction, DocValuesMissingTest, TestCustomDocTransformer, URLClassifyProcessorTest, HdfsSyncSliceTest, TestStressCloudBlindAtomicUpdates, SmileWriterTest, TestNamedUpdateProcessors, TestCustomSort, DeleteInactiveReplicaTest, DistribCursorPagingTest, TestPHPSerializedResponseWriter, TestConfig, TestShortCircuitedRequests, DistributedFacetPivotWhiteBoxTest, AliasIntegrationTest, RecoveryAfterSoftCommitTest, ShardRoutingCustomTest, TestInitParams, TestJsonFacetRefinement, TestEmbeddedSolrServerConstructors, TestFieldTypeCollectionResource, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, TestRandomFaceting, TestZkChroot, TestDistributedSearch]
[junit4] Completed [335/648 (1!)] on J1 in 72.70s, 1 test, 1 failure <<< FAILURES!
[...truncated 53776 lines...]