[JENKINS] Lucene-Solr-Tests-6.x - Build # 920 - Unstable

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/920/

1 tests failed.
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([546AF37761F23958:DC3ECCADCF0E54A0]: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:1176)
        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1117)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:977)
        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:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 11679 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001/init-core-data-001
   [junit4]   2> 486329 WARN  
(SUITE-TestDistributedSearch-seed#[546AF37761F23958]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 486329 INFO  
(SUITE-TestDistributedSearch-seed#[546AF37761F23958]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 486331 INFO  
(SUITE-TestDistributedSearch-seed#[546AF37761F23958]-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> 486331 INFO  
(SUITE-TestDistributedSearch-seed#[546AF37761F23958]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: 
/rd_yvq/tr
   [junit4]   2> 486499 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001/tempDir-001/control/cores/collection1
   [junit4]   2> 486502 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 486503 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@593f35b5{/rd_yvq/tr,null,AVAILABLE}
   [junit4]   2> 486505 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@5eb00dad{HTTP/1.1,[http/1.1]}{127.0.0.1:32932}
   [junit4]   2> 486505 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] o.e.j.s.Server 
Started @489448ms
   [junit4]   2> 486505 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/rd_yvq/tr, 
hostPort=32932, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001/tempDir-001/control/cores}
   [junit4]   2> 486505 ERROR 
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 486505 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.7.0
   [junit4]   2> 486505 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 486505 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 486505 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-12T05:34:08.970Z
   [junit4]   2> 486513 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001/tempDir-001/control/solr.xml
   [junit4]   2> 486526 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 486556 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001/tempDir-001/control/cores
   [junit4]   2> 486556 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 486559 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 486642 WARN  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 486643 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 486671 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 486812 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 486818 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 486819 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 486819 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 486819 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7b78ed51
   [junit4]   2> 486820 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=58.3876953125, 
floorSegmentMB=0.9794921875, forceMergeDeletesPctAllowed=21.842497754239215, 
segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5931689199027605
   [junit4]   2> 486884 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 486884 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 486885 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 486885 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 486886 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.12578411188503483]
   [junit4]   2> 486886 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5e1b03b7[collection1] main]
   [junit4]   2> 486886 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 486894 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 486931 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 486931 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 486931 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 2147483647 transient cores
   [junit4]   2> 486932 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 486932 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 486932 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 486932 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 486932 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 486932 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 486933 INFO  (coreLoadExecutor-1943-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1569975854063157248
   [junit4]   2> 486933 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 486933 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 486934 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5e1b03b7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 487210 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 487211 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 487212 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3be76674{/rd_yvq/tr,null,AVAILABLE}
   [junit4]   2> 487213 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@7b1886dc{HTTP/1.1,[http/1.1]}{127.0.0.1:53626}
   [junit4]   2> 487213 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] o.e.j.s.Server 
Started @490156ms
   [junit4]   2> 487213 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/rd_yvq/tr, 
hostPort=53626, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001/tempDir-001/shard0/cores}
   [junit4]   2> 487213 ERROR 
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 487213 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.7.0
   [junit4]   2> 487213 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 487213 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 487213 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-12T05:34:09.678Z
   [junit4]   2> 487221 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 487228 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 487254 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001/tempDir-001/shard0/cores
   [junit4]   2> 487255 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 487255 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 487293 WARN  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 487297 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 487324 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 487446 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 487452 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 487453 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 487453 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 487453 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7b78ed51
   [junit4]   2> 487454 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=21, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=58.3876953125, 
floorSegmentMB=0.9794921875, forceMergeDeletesPctAllowed=21.842497754239215, 
segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5931689199027605
   [junit4]   2> 487516 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 487516 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 487525 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 487525 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 487526 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=17, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.12578411188503483]
   [junit4]   2> 487526 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@292b6296[collection1] main]
   [junit4]   2> 487526 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 487534 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 487561 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 487561 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 487561 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 2147483647 transient cores
   [junit4]   2> 487561 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 487561 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 487561 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 487562 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 487562 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 487562 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 487562 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 487562 INFO  (coreLoadExecutor-1954-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1569975854722711552
   [junit4]   2> 487563 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 487566 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@292b6296[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 487763 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 487772 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{deleteByQuery=*:* 
(-1569975854935572480)} 0 7
   [junit4]   2> 487776 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{deleteByQuery=*:* 
(-1569975854945009664)} 0 1
   [junit4]   2> 487780 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[1 (1569975854949203968)]} 0 1
   [junit4]   2> 487783 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[1 (1569975854953398272)]} 0 1
   [junit4]   2> 487785 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[2 (1569975854956544000)]} 0 0
   [junit4]   2> 487791 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[2 (1569975854961786880)]} 0 0
   [junit4]   2> 487793 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[3 (1569975854963884032)]} 0 0
   [junit4]   2> 487795 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[3 (1569975854965981184)]} 0 0
   [junit4]   2> 487797 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[4 (1569975854968078336)]} 0 0
   [junit4]   2> 487799 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[4 (1569975854970175488)]} 0 0
   [junit4]   2> 487801 INFO  (qtp1868954617-4920) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[5 (1569975854973321216)]} 0 0
   [junit4]   2> 487804 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[5 (1569975854975418368)]} 0 0
   [junit4]   2> 487810 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[6 (1569975854982758400)]} 0 0
   [junit4]   2> 487812 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[6 (1569975854984855552)]} 0 0
   [junit4]   2> 487814 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[7 (1569975854986952704)]} 0 0
   [junit4]   2> 487816 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[7 (1569975854989049856)]} 0 0
   [junit4]   2> 487819 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[8 (1569975854991147008)]} 0 0
   [junit4]   2> 487821 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[8 (1569975854993244160)]} 0 0
   [junit4]   2> 487832 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[9 (1569975855005827072)]} 0 0
   [junit4]   2> 487834 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[9 (1569975855007924224)]} 0 0
   [junit4]   2> 487836 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 487836 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2e941660
   [junit4]   2> 487842 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@a63a999[collection1] main]
   [junit4]   2> 487843 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 487844 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a63a999[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c9)))}
   [junit4]   2> 487845 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 8
   [junit4]   2> 487847 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 487847 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4e434808
   [junit4]   2> 487851 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@557ce512[collection1] main]
   [junit4]   2> 487851 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 487853 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@557ce512[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c9)))}
   [junit4]   2> 487853 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 6
   [junit4]   2> 487867 INFO  (qtp1868954617-4920) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[10 (1569975855029944320)]} 0 12
   [junit4]   2> 487874 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[10 (1569975855047770112)]} 0 1
   [junit4]   2> 487877 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[11 (1569975855051964416)]} 0 0
   [junit4]   2> 487883 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[11 (1569975855054061568)]} 0 4
   [junit4]   2> 487886 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[12 (1569975855061401600)]} 0 0
   [junit4]   2> 487888 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[12 (1569975855064547328)]} 0 0
   [junit4]   2> 487890 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[13 (1569975855066644480)]} 0 0
   [junit4]   2> 487893 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[13 (1569975855068741632)]} 0 0
   [junit4]   2> 487895 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[1001 (1569975855070838784)]} 0 0
   [junit4]   2> 487897 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[1001 (1569975855073984512)]} 0 0
   [junit4]   2> 487899 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[14 (1569975855076081664)]} 0 0
   [junit4]   2> 487901 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[14 (1569975855078178816)]} 0 0
   [junit4]   2> 487903 INFO  (qtp1868954617-4920) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[15 (1569975855080275968)]} 0 0
   [junit4]   2> 487905 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[15 (1569975855082373120)]} 0 0
   [junit4]   2> 487907 INFO  (qtp1868954617-4920) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[16 (1569975855084470272)]} 0 0
   [junit4]   2> 487909 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[16 (1569975855086567424)]} 0 0
   [junit4]   2> 487915 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[17 (1569975855091810304)]} 0 0
   [junit4]   2> 487917 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[17 (1569975855094956032)]} 0 0
   [junit4]   2> 487919 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[100 (1569975855097053184)]} 0 0
   [junit4]   2> 487921 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[100 (1569975855099150336)]} 0 0
   [junit4]   2> 487923 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[101 (1569975855101247488)]} 0 0
   [junit4]   2> 487925 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[101 (1569975855102296064)]} 0 0
   [junit4]   2> 487927 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[102 (1569975855104393216)]} 0 0
   [junit4]   2> 487929 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[102 (1569975855106490368)]} 0 0
   [junit4]   2> 487930 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[103 (1569975855108587520)]} 0 0
   [junit4]   2> 487932 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[103 (1569975855110684672)]} 0 0
   [junit4]   2> 487934 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[104 (1569975855112781824)]} 0 0
   [junit4]   2> 487936 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[104 (1569975855114878976)]} 0 0
   [junit4]   2> 487938 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[105 (1569975855116976128)]} 0 0
   [junit4]   2> 487940 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[105 (1569975855119073280)]} 0 0
   [junit4]   2> 487942 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[106 (1569975855121170432)]} 0 0
   [junit4]   2> 487944 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[106 (1569975855123267584)]} 0 0
   [junit4]   2> 487946 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[107 (1569975855125364736)]} 0 0
   [junit4]   2> 487948 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[107 (1569975855127461888)]} 0 0
   [junit4]   2> 487950 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[108 (1569975855129559040)]} 0 0
   [junit4]   2> 487957 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[108 (1569975855136899072)]} 0 0
   [junit4]   2> 487959 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[109 (1569975855138996224)]} 0 0
   [junit4]   2> 487962 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[109 (1569975855141093376)]} 0 0
   [junit4]   2> 487964 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[110 (1569975855144239104)]} 0 0
   [junit4]   2> 487966 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[110 (1569975855145287680)]} 0 0
   [junit4]   2> 487968 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[111 (1569975855147384832)]} 0 0
   [junit4]   2> 487970 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[111 (1569975855149481984)]} 0 0
   [junit4]   2> 487972 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[112 (1569975855151579136)]} 0 0
   [junit4]   2> 487974 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[112 (1569975855153676288)]} 0 0
   [junit4]   2> 487976 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[113 (1569975855155773440)]} 0 0
   [junit4]   2> 487978 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[113 (1569975855157870592)]} 0 0
   [junit4]   2> 487980 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[114 (1569975855159967744)]} 0 0
   [junit4]   2> 487982 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[114 (1569975855162064896)]} 0 0
   [junit4]   2> 487984 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[115 (1569975855164162048)]} 0 0
   [junit4]   2> 487986 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[115 (1569975855166259200)]} 0 0
   [junit4]   2> 487990 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[116 (1569975855171502080)]} 0 0
   [junit4]   2> 487992 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[116 (1569975855173599232)]} 0 0
   [junit4]   2> 487994 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[117 (1569975855175696384)]} 0 0
   [junit4]   2> 487996 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[117 (1569975855177793536)]} 0 0
   [junit4]   2> 487998 INFO  (qtp1868954617-4926) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[118 (1569975855179890688)]} 0 0
   [junit4]   2> 488000 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[118 (1569975855181987840)]} 0 0
   [junit4]   2> 488002 INFO  (qtp1868954617-4926) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[119 (1569975855184084992)]} 0 0
   [junit4]   2> 488004 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[119 (1569975855186182144)]} 0 0
   [junit4]   2> 488006 INFO  (qtp1868954617-4926) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[120 (1569975855188279296)]} 0 0
   [junit4]   2> 488008 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[120 (1569975855190376448)]} 0 0
   [junit4]   2> 488010 INFO  (qtp1868954617-4926) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[121 (1569975855192473600)]} 0 0
   [junit4]   2> 488012 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[121 (1569975855194570752)]} 0 0
   [junit4]   2> 488014 INFO  (qtp1868954617-4926) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[122 (1569975855196667904)]} 0 0
   [junit4]   2> 488016 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[122 (1569975855197716480)]} 0 0
   [junit4]   2> 488018 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[123 (1569975855200862208)]} 0 0
   [junit4]   2> 488020 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[123 (1569975855202959360)]} 0 0
   [junit4]   2> 488022 INFO  (qtp1868954617-4920) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[124 (1569975855205056512)]} 0 0
   [junit4]   2> 488024 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[124 (1569975855207153664)]} 0 0
   [junit4]   2> 488026 INFO  (qtp1868954617-4920) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[125 (1569975855209250816)]} 0 0
   [junit4]   2> 488028 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[125 (1569975855211347968)]} 0 0
   [junit4]   2> 488030 INFO  (qtp1868954617-4920) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[126 (1569975855212396544)]} 0 0
   [junit4]   2> 488032 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[126 (1569975855214493696)]} 0 0
   [junit4]   2> 488034 INFO  (qtp1868954617-4920) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[127 (1569975855216590848)]} 0 0
   [junit4]   2> 488036 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[127 (1569975855218688000)]} 0 0
   [junit4]   2> 488038 INFO  (qtp1868954617-4920) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[128 (1569975855220785152)]} 0 0
   [junit4]   2> 488040 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[128 (1569975855222882304)]} 0 0
   [junit4]   2> 488042 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[129 (1569975855226028032)]} 0 0
   [junit4]   2> 488044 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[129 (1569975855227076608)]} 0 0
   [junit4]   2> 488046 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[130 (1569975855230222336)]} 0 0
   [junit4]   2> 488048 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[130 (1569975855232319488)]} 0 0
   [junit4]   2> 488050 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[131 (1569975855234416640)]} 0 0
   [junit4]   2> 488052 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[131 (1569975855236513792)]} 0 0
   [junit4]   2> 488056 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[132 (1569975855240708096)]} 0 0
   [junit4]   2> 488058 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[132 (1569975855242805248)]} 0 0
   [junit4]   2> 488060 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[133 (1569975855244902400)]} 0 0
   [junit4]   2> 488062 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[133 (1569975855246999552)]} 0 0
   [junit4]   2> 488064 INFO  (qtp1868954617-4925) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[134 (1569975855249096704)]} 0 0
   [junit4]   2> 488066 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[134 (1569975855250145280)]} 0 0
   [junit4]   2> 488068 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[135 (1569975855252242432)]} 0 0
   [junit4]   2> 488070 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[135 (1569975855254339584)]} 0 0
   [junit4]   2> 488071 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[136 (1569975855256436736)]} 0 0
   [junit4]   2> 488073 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[136 (1569975855258533888)]} 0 0
   [junit4]   2> 488075 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[137 (1569975855260631040)]} 0 0
   [junit4]   2> 488077 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[137 (1569975855262728192)]} 0 0
   [junit4]   2> 488079 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[138 (1569975855264825344)]} 0 0
   [junit4]   2> 488081 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[138 (1569975855266922496)]} 0 0
   [junit4]   2> 488083 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[139 (1569975855269019648)]} 0 0
   [junit4]   2> 488085 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[139 (1569975855271116800)]} 0 0
   [junit4]   2> 488087 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[140 (1569975855273213952)]} 0 0
   [junit4]   2> 488089 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[140 (1569975855275311104)]} 0 0
   [junit4]   2> 488091 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[141 (1569975855277408256)]} 0 0
   [junit4]   2> 488093 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[141 (1569975855279505408)]} 0 0
   [junit4]   2> 488095 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[142 (1569975855281602560)]} 0 0
   [junit4]   2> 488097 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[142 (1569975855283699712)]} 0 0
   [junit4]   2> 488099 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[143 (1569975855285796864)]} 0 0
   [junit4]   2> 488101 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[143 (1569975855287894016)]} 0 0
   [junit4]   2> 488103 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[144 (1569975855289991168)]} 0 0
   [junit4]   2> 488105 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[144 (1569975855292088320)]} 0 0
   [junit4]   2> 488107 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[145 (1569975855294185472)]} 0 0
   [junit4]   2> 488109 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[145 (1569975855296282624)]} 0 0
   [junit4]   2> 488111 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[146 (1569975855298379776)]} 0 0
   [junit4]   2> 488113 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[146 (1569975855300476928)]} 0 0
   [junit4]   2> 488115 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[147 (1569975855302574080)]} 0 0
   [junit4]   2> 488117 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[147 (1569975855303622656)]} 0 0
   [junit4]   2> 488125 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[148 (1569975855313059840)]} 0 0
   [junit4]   2> 488128 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[148 (1569975855315156992)]} 0 0
   [junit4]   2> 488134 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[149 (1569975855321448448)]} 0 0
   [junit4]   2> 488136 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update params={wt=javabin&version=2}{add=[149 (1569975855323545600)]} 0 0
   [junit4]   2> 488137 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 488137 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2e941660
   [junit4]   2> 488155 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4558884b[collection1] main]
   [junit4]   2> 488155 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 488157 INFO  
(searcherExecutor-1944-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4558884b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c9)
 Uninverting(_1(6.7.0):c59)))}
   [junit4]   2> 488157 INFO  (qtp1868954617-4923) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 19
   [junit4]   2> 488159 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 488159 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4e434808
   [junit4]   2> 488183 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7fd51975[collection1] main]
   [junit4]   2> 488185 INFO  
(searcherExecutor-1955-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7fd51975[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c9)
 Uninverting(_1(6.7.0):c59)))}
   [junit4]   2> 488185 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 488185 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rd_yvq/tr 
path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 26
   [junit4]   2> 488188 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 488188 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 488191 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 488191 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 488193 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488290 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/rd_yvq/tr|[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650661&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488296 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650661&ids=121,143,1,111,135,124,5,138,6,130&isShard=true&shard.url=[ff01::083]:33332/rd_yvq/tr|[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488298 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=[ff01::083]:33332/rd_yvq/tr|[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=101
   [junit4]   2> 488300 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488306 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::213]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650768&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488309 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650768&ids=133,123,125,105,116,139,1001,7,8,141&isShard=true&shard.url=[ff01::213]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488311 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=[ff01::213]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 488314 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488319 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650782&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488323 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650782&ids=122,144,2,3,103,147,148,105,149,142&isShard=true&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488325 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 488327 INFO  (qtp1868954617-4924) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488332 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650795&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488335 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650795&ids=110,112,124,114,15,127,129,119,141,10&isShard=true&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488337 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 488340 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488344 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650807&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488347 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650807&ids=121,110,1,112,145,123,136,148,129,119&isShard=true&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488348 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 488350 INFO  (qtp1868954617-4926) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488408 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr&rows=10&version=2&q=*:*&NOW=1497245650818&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488411 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650818&ids=11,132,122,144,101,135,128,118,8,142&isShard=true&shard.url=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488413 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=59
   [junit4]   2> 488415 INFO  (qtp1868954617-4926) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488419 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650882&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488422 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650882&ids=121,144,135,103,127,138,149,140,120,10&isShard=true&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488423 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 488425 INFO  (qtp1868954617-4926) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488430 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650893&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488433 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650893&ids=133,134,112,102,15,147,137,106,119,142&isShard=true&shard.url=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488434 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 488436 INFO  (qtp1868954617-4926) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488441 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650904&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488444 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650904&ids=147,4,125,103,137,107,118,140,142,131&isShard=true&shard.url=[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488445 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 488447 INFO  (qtp1868954617-4926) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488452 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr&rows=10&version=2&q=*:*&NOW=1497245650915&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488454 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650915&ids=121,111,101,145,135,15,115,16,116,139&isShard=true&shard.url=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488455 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 488457 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488462 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr&rows=10&version=2&q=*:*&NOW=1497245650925&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488464 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650925&ids=145,134,2,112,123,146,118,130,10,142&isShard=true&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488465 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 488468 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488472 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr&rows=10&version=2&q=*:*&NOW=1497245650935&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488475 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650935&ids=121,133,102,124,3,127,107,119,109,140&isShard=true&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488476 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 488479 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488483 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650946&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488487 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650946&ids=110,111,2,124,103,15,129,8,10,120&isShard=true&shard.url=[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488488 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr|[ff01::083]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 488490 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488495 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650958&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488497 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650958&ids=132,133,146,135,14,147,114,4,148,140&isShard=true&shard.url=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488498 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 488503 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488508 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::083]:33332/rd_yvq/tr|[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650971&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488511 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650971&ids=133,111,100,12,123,14,115,5,149,10&isShard=true&shard.url=[ff01::083]:33332/rd_yvq/tr|[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488512 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=[ff01::083]:33332/rd_yvq/tr|[ff01::213]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 488514 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488521 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650984&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488524 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650984&ids=121,145,112,113,124,147,103,138,1001,118&isShard=true&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488525 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 488527 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488532 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=[ff01::083]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245650994&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488534 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245650994&ids=110,146,114,4,16,138,7,140,141,131&isShard=true&shard.url=[ff01::083]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488535 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=[ff01::083]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 488537 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488541 INFO  (qtp542258989-4942) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245651004&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488544 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245651004&ids=133,112,113,147,125,5,105,116,128,130&isShard=true&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488545 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 488547 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=a_i_p+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 488552 INFO  (qtp542258989-4943) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_p+desc&shard.url=[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::083]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr&rows=10&version=2&q=*:*&NOW=1497245651015&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488555 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245651015&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::083]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488556 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::083]:33332/rd_yvq/tr|[ff01::114]:33332/rd_yvq/tr&sort=a_i_p+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 488558 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=0
   [junit4]   2> 488563 INFO  (qtp542258989-4940) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i_p,5))+desc&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=*:*&NOW=1497245651026&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488566 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497245651026&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488567 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&shards=http://127.0.0.1:53626/rd_yvq/tr/collection1&sort={!func}testfunc(add(a_i_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 488571 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=a_i_p[*+TO+*]&distrib=false&sort=a_i_p+asc&wt=javabin&version=2} 
hits=13 status=0 QTime=1
   [junit4]   2> 488576 INFO  (qtp542258989-4938) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_p+asc&shard.url=[ff01::083]:33332/rd_yvq/tr|[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&rows=10&version=2&q=a_i_p[*+TO+*]&NOW=1497245651038&isShard=true&wt=javabin}
 hits=13 status=0 QTime=0
   [junit4]   2> 488579 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=a_i_p[*+TO+*]&df=text&distrib=false&shards.purpose=64&NOW=1497245651038&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::083]:33332/rd_yvq/tr|[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 488580 INFO  (qtp542258989-4941) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=a_i_p[*+TO+*]&shards=[ff01::083]:33332/rd_yvq/tr|[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:53626/rd_yvq/tr/collection1&sort=a_i_p+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=6
   [junit4]   2> 488583 INFO  (qtp1868954617-4927) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&distrib=false&sort=a_i_p+asc,+id+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 488587 INFO  (qtp542258989-4944) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_p+asc,+id+desc&shard.url=http://127.0.0.1:53626/rd_yvq/tr/collection1|[ff01::114]:33332/rd_yvq/tr&rows=10&version=2&q=*:*&NOW=1497245651050&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 488589 INFO  (qtp542258989-4937) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={q=*:*&df=text&distrib=false&shards.purp

[...truncated too long message...]

69) [    x:collection1] o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr 
path=/select 
params={distrib=false&fl=id,a_i_p&group.limit=10&sort=a_i_p+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=4
   [junit4]   2> 571140 WARN  (qtp248166654-5005) [    x:collection1] 
o.a.s.h.c.SearchHandler Query: 
df=text&distrib=false&fl=id&group.limit=10&shards.purpose=4&start=0&sort=a_i_p+asc,+id+asc&shards.info=true&shard.url=http://127.0.0.1:50227/rd_yvq/tr/collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&group.distributed.second=true&q=*:*&shards.tolerant=true&NOW=1497245733464&isShard=true&timeAllowed=1&wt=javabin&group=true;
 The request took too long to iterate over terms. Timeout: timeoutAt: 
4116206056295009 (System.nanoTime(): 4116206058135434), 
TermsEnum=org.apache.lucene.codecs.blocktree.SegmentTermsEnum@51ddd73a
   [junit4]   2> 571177 INFO  (qtp248166654-5005) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={df=text&distrib=false&fl=id&group.limit=10&shards.purpose=4&start=0&sort=a_i_p+asc,+id+asc&shards.info=true&shard.url=http://127.0.0.1:50227/rd_yvq/tr/collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&group.distributed.second=true&q=*:*&shards.tolerant=true&NOW=1497245733464&isShard=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=40
   [junit4]   2> 571179 INFO  (qtp248166654-4999) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rd_yvq/tr path=/select 
params={fl=id,a_i_p&group.limit=10&sort=a_i_p+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=[ff01::213]:33332/rd_yvq/tr|http://127.0.0.1:32968/rd_yvq/tr/collection1,http://127.0.0.1:50227/rd_yvq/tr/collection1&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=180
   [junit4]   2> 571180 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 571186 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@5ba42640{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 571186 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1604962329
   [junit4]   2> 571186 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 571186 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 571187 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 571208 INFO  (coreCloseExecutor-1997-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@428a707d
   [junit4]   2> 571292 INFO  (coreCloseExecutor-1997-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 571293 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@55af3b2c{/rd_yvq/tr,null,UNAVAILABLE}
   [junit4]   2> 571300 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@77be7d20{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 571300 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=195915096
   [junit4]   2> 571300 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 571300 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 571300 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 571311 INFO  (coreCloseExecutor-1999-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@78d0db99
   [junit4]   2> 571446 INFO  (coreCloseExecutor-1999-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 571447 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@38aa6e44{/rd_yvq/tr,null,UNAVAILABLE}
   [junit4]   2> 571449 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@54a12da3{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 571449 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=348908315
   [junit4]   2> 571449 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 571449 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 571449 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 571457 INFO  (coreCloseExecutor-2001-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@20f6e77b
   [junit4]   2> 571527 INFO  (coreCloseExecutor-2001-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 571531 INFO  
(TEST-TestDistributedSearch.test-seed#[546AF37761F23958]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7080f8a5{/rd_yvq/tr,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=546AF37761F23958 -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=es-PA -Dtests.timezone=NZ -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE 85.2s J0 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected to find 
shardAddress in the up shard info
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([546AF37761F23958:DC3ECCADCF0E54A0]:0)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1176)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1117)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:977)
   [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:748)
   [junit4]   2> 571543 INFO  
(SUITE-TestDistributedSearch-seed#[546AF37761F23958]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_546AF37761F23958-001
   [junit4]   2> NOTE: test params are: codec=Lucene62, 
sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=es-PA, timezone=NZ
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=1,free=265108464,total=520093696
   [junit4]   2> NOTE: All tests run in this JVM: [SimpleMLTQParserTest, 
TestCustomStream, DistributedQueryElevationComponentTest, 
TestFieldCacheVsDocValues, TestBinaryResponseWriter, 
DocumentAnalysisRequestHandlerTest, XsltUpdateRequestHandlerTest, 
DistributedQueryComponentCustomSortTest, ClusterStateTest, 
TlogReplayBufferedWhileIndexingTest, PathHierarchyTokenizerFactoryTest, 
TestReplicationHandlerBackup, TestStandardQParsers, 
AnalysisAfterCoreReloadTest, LargeFieldTest, TestJettySolrRunner, 
HdfsUnloadDistributedZkTest, BasicDistributedZkTest, TestPerFieldSimilarity, 
ClassificationUpdateProcessorTest, RegexBoostProcessorTest, 
SolrRequestParserTest, LeaderElectionIntegrationTest, TestDocTermOrds, 
TestStressLucene, TestIBSimilarityFactory, HighlighterConfigTest, 
TestHighFrequencyDictionaryFactory, HdfsBasicDistributedZk2Test, 
TestRandomFaceting, TestManagedResourceStorage, DocValuesNotIndexedTest, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, ScriptEngineTest, 
SuggestComponentTest, TestPathTrie, DistributedFacetExistsSmallTest, 
TestExactSharedStatsCache, AddBlockUpdateTest, 
DistributedSpellCheckComponentTest, SolrCLIZkUtilsTest, UtilsToolTest, 
TestXmlQParser, TestDynamicFieldResource, CacheHeaderTest, 
CdcrReplicationHandlerTest, BaseCdcrDistributedZkTest, TestRandomDVFaceting, 
TestFunctionQuery, TestSolrCloudWithSecureImpersonation, ResourceLoaderTest, 
BlockJoinFacetRandomTest, TestSolrConfigHandlerConcurrent, ZkNodePropsTest, 
TestSQLHandler, TestRangeQuery, TestRealTimeGet, 
TestSlowCompositeReaderWrapper, FacetPivotSmallTest, TestSolr4Spatial, 
QueryElevationComponentTest, BasicZkTest, TestSchemaResource, 
TolerantUpdateProcessorTest, TestDownShardTolerantSearch, TestFastLRUCache, 
CoreAdminCreateDiscoverTest, SolrSlf4jReporterTest, TestCoreAdminApis, 
TestFastWriter, TestHighlightDedupGrouping, FullHLLTest, 
TestFieldTypeCollectionResource, QueryEqualityTest, TestDistributedSearch]
   [junit4] Completed [219/715 (1!)] on J0 in 85.27s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 45086 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org