lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_102) - Build # 549 - Unstable!
Date Sun, 30 Oct 2016 05:29:36 GMT
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...]


Mime
View raw message