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

[Apache Jenkins Server]

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

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([7D611F00C2B01A8E:F53520DA6C4C7776]: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:745)




Build Log:
[...truncated 11727 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_7D611F00C2B01A8E-001/init-core-data-001
   [junit4]   2> 546057 WARN  
(SUITE-TestDistributedSearch-seed#[7D611F00C2B01A8E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 546057 INFO  
(SUITE-TestDistributedSearch-seed#[7D611F00C2B01A8E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 546059 INFO  
(SUITE-TestDistributedSearch-seed#[7D611F00C2B01A8E]-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> 546059 INFO  
(SUITE-TestDistributedSearch-seed#[7D611F00C2B01A8E]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /o_/
   [junit4]   2> 546175 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
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_7D611F00C2B01A8E-001/tempDir-001/control/cores/collection1
   [junit4]   2> 546176 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 546184 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@56098929{/o_,null,AVAILABLE}
   [junit4]   2> 546186 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@117630a0{HTTP/1.1,[http/1.1]}{127.0.0.1:40533}
   [junit4]   2> 546186 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] o.e.j.s.Server 
Started @549398ms
   [junit4]   2> 546186 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/o_, hostPort=40533, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7D611F00C2B01A8E-001/tempDir-001/control/cores}
   [junit4]   2> 546186 ERROR 
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 546186 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.6.0
   [junit4]   2> 546186 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 546186 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 546186 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-06T09:39:55.290Z
   [junit4]   2> 546187 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
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_7D611F00C2B01A8E-001/tempDir-001/control/solr.xml
   [junit4]   2> 546200 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 546227 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
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_7D611F00C2B01A8E-001/tempDir-001/control/cores
   [junit4]   2> 546227 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 546239 INFO  (coreLoadExecutor-2508-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> 546291 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 546319 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 546441 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 546448 INFO  (coreLoadExecutor-2508-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> 546448 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 546448 INFO  (coreLoadExecutor-2508-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_7D611F00C2B01A8E-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 546448 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ade0059
   [junit4]   2> 546449 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=32, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5359840151986907]
   [junit4]   2> 546511 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 546511 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 546512 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 546512 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 546513 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=44, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=44.37890625, 
floorSegmentMB=0.5166015625, forceMergeDeletesPctAllowed=16.307483506865825, 
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.23997981443138247
   [junit4]   2> 546513 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b743b0[collection1] 
main]
   [junit4]   2> 546513 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 546524 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 546557 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 546558 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 546558 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 2147483647 transient cores
   [junit4]   2> 546558 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 546559 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 546559 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 546559 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 546559 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 546559 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 546560 INFO  (coreLoadExecutor-2508-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1566639228886974464
   [junit4]   2> 546560 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 546560 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 546561 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5b743b0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 547033 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
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_7D611F00C2B01A8E-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 547035 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 547037 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@135ce35c{/o_,null,AVAILABLE}
   [junit4]   2> 547039 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@47c858bd{HTTP/1.1,[http/1.1]}{127.0.0.1:51628}
   [junit4]   2> 547039 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] o.e.j.s.Server 
Started @550251ms
   [junit4]   2> 547039 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/o_, hostPort=51628, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7D611F00C2B01A8E-001/tempDir-001/shard0/cores}
   [junit4]   2> 547040 ERROR 
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 547040 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.6.0
   [junit4]   2> 547040 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 547040 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 547040 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-06T09:39:56.144Z
   [junit4]   2> 547041 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
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_7D611F00C2B01A8E-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 547048 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 547077 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
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_7D611F00C2B01A8E-001/tempDir-001/shard0/cores
   [junit4]   2> 547077 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 547078 INFO  (coreLoadExecutor-2519-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> 547124 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 547151 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 547274 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 547281 INFO  (coreLoadExecutor-2519-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> 547281 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 547281 INFO  (coreLoadExecutor-2519-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_7D611F00C2B01A8E-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 547281 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ade0059
   [junit4]   2> 547283 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=32, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5359840151986907]
   [junit4]   2> 547348 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 547348 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 547348 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 547348 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 547350 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=44, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=44.37890625, 
floorSegmentMB=0.5166015625, forceMergeDeletesPctAllowed=16.307483506865825, 
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.23997981443138247
   [junit4]   2> 547350 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a211b7[collection1] 
main]
   [junit4]   2> 547350 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 547359 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 547387 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 547387 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 547387 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 2147483647 transient cores
   [junit4]   2> 547387 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 547387 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 547387 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 547388 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 547388 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 547388 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 547389 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 547389 INFO  (coreLoadExecutor-2519-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1566639229756243968
   [junit4]   2> 547389 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 547390 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6a211b7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 547789 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 547795 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1566639230177771520)} 0 3
   [junit4]   2> 547801 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1566639230186160128)} 0 1
   [junit4]   2> 547811 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[1 (1566639230192451584)]} 0 6
   [junit4]   2> 547816 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[1 (1566639230201888768)]} 0 2
   [junit4]   2> 547820 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[2 (1566639230207131648)]} 0 0
   [junit4]   2> 547825 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[2 (1566639230212374528)]} 0 0
   [junit4]   2> 547827 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[3 (1566639230215520256)]} 0 0
   [junit4]   2> 547830 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[3 (1566639230217617408)]} 0 0
   [junit4]   2> 547833 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[4 (1566639230220763136)]} 0 0
   [junit4]   2> 547835 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[4 (1566639230223908864)]} 0 0
   [junit4]   2> 547838 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[5 (1566639230226006016)]} 0 0
   [junit4]   2> 547841 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[5 (1566639230230200320)]} 0 0
   [junit4]   2> 547844 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[6 (1566639230233346048)]} 0 0
   [junit4]   2> 547846 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[6 (1566639230235443200)]} 0 0
   [junit4]   2> 547849 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[7 (1566639230237540352)]} 0 0
   [junit4]   2> 547852 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[7 (1566639230240686080)]} 0 0
   [junit4]   2> 547854 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[8 (1566639230243831808)]} 0 0
   [junit4]   2> 547857 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[8 (1566639230245928960)]} 0 0
   [junit4]   2> 547859 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[9 (1566639230249074688)]} 0 0
   [junit4]   2> 547861 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[9 (1566639230251171840)]} 0 0
   [junit4]   2> 547863 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 547863 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7852e53
   [junit4]   2> 547876 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@15d2f3b2[collection1] main]
   [junit4]   2> 547879 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@15d2f3b2[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c9)))}
   [junit4]   2> 547879 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 547879 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 16
   [junit4]   2> 547881 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 547881 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@19b05dc8
   [junit4]   2> 547889 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5926d7a[collection1] main]
   [junit4]   2> 547891 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 547891 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5926d7a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c9)))}
   [junit4]   2> 547894 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 12
   [junit4]   2> 547899 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[10 (1566639230288920576)]} 0 2
   [junit4]   2> 547904 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[10 (1566639230294163456)]} 0 2
   [junit4]   2> 547907 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[11 (1566639230298357760)]} 0 0
   [junit4]   2> 547910 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[11 (1566639230301503488)]} 0 0
   [junit4]   2> 547912 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[12 (1566639230304649216)]} 0 0
   [junit4]   2> 547915 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[12 (1566639230306746368)]} 0 0
   [junit4]   2> 547917 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[13 (1566639230308843520)]} 0 0
   [junit4]   2> 547919 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[13 (1566639230311989248)]} 0 0
   [junit4]   2> 547922 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[1001 (1566639230314086400)]} 0 0
   [junit4]   2> 547925 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[1001 (1566639230317232128)]} 0 1
   [junit4]   2> 547928 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[14 (1566639230320377856)]} 0 0
   [junit4]   2> 547930 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[14 (1566639230323523584)]} 0 0
   [junit4]   2> 547932 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[15 (1566639230325620736)]} 0 0
   [junit4]   2> 547935 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[15 (1566639230327717888)]} 0 0
   [junit4]   2> 547937 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[16 (1566639230330863616)]} 0 0
   [junit4]   2> 547940 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[16 (1566639230332960768)]} 0 0
   [junit4]   2> 547942 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[17 (1566639230336106496)]} 0 0
   [junit4]   2> 547945 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[17 (1566639230338203648)]} 0 0
   [junit4]   2> 547947 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[100 (1566639230341349376)]} 0 0
   [junit4]   2> 547949 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[100 (1566639230343446528)]} 0 0
   [junit4]   2> 547952 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[101 (1566639230345543680)]} 0 0
   [junit4]   2> 547954 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[101 (1566639230347640832)]} 0 0
   [junit4]   2> 547956 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[102 (1566639230350786560)]} 0 0
   [junit4]   2> 547958 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[102 (1566639230352883712)]} 0 0
   [junit4]   2> 547960 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[103 (1566639230354980864)]} 0 0
   [junit4]   2> 547962 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[103 (1566639230357078016)]} 0 0
   [junit4]   2> 547964 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[104 (1566639230359175168)]} 0 0
   [junit4]   2> 547967 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[104 (1566639230361272320)]} 0 0
   [junit4]   2> 547969 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[105 (1566639230363369472)]} 0 0
   [junit4]   2> 547971 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[105 (1566639230366515200)]} 0 0
   [junit4]   2> 547973 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[106 (1566639230368612352)]} 0 0
   [junit4]   2> 547975 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[106 (1566639230370709504)]} 0 0
   [junit4]   2> 547978 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[107 (1566639230372806656)]} 0 0
   [junit4]   2> 547980 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[107 (1566639230374903808)]} 0 0
   [junit4]   2> 547982 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[108 (1566639230378049536)]} 0 0
   [junit4]   2> 547984 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[108 (1566639230380146688)]} 0 0
   [junit4]   2> 547986 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[109 (1566639230382243840)]} 0 0
   [junit4]   2> 547989 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[109 (1566639230384340992)]} 0 0
   [junit4]   2> 547991 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[110 (1566639230386438144)]} 0 0
   [junit4]   2> 547993 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[110 (1566639230389583872)]} 0 0
   [junit4]   2> 547995 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[111 (1566639230391681024)]} 0 0
   [junit4]   2> 547997 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[111 (1566639230393778176)]} 0 0
   [junit4]   2> 547999 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[112 (1566639230395875328)]} 0 0
   [junit4]   2> 548002 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[112 (1566639230397972480)]} 0 0
   [junit4]   2> 548004 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[113 (1566639230400069632)]} 0 0
   [junit4]   2> 548006 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[113 (1566639230402166784)]} 0 0
   [junit4]   2> 548008 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[114 (1566639230404263936)]} 0 0
   [junit4]   2> 548010 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[114 (1566639230406361088)]} 0 0
   [junit4]   2> 548012 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[115 (1566639230409506816)]} 0 0
   [junit4]   2> 548014 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[115 (1566639230411603968)]} 0 0
   [junit4]   2> 548016 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[116 (1566639230413701120)]} 0 0
   [junit4]   2> 548018 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[116 (1566639230415798272)]} 0 0
   [junit4]   2> 548021 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[117 (1566639230417895424)]} 0 0
   [junit4]   2> 548023 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[117 (1566639230419992576)]} 0 0
   [junit4]   2> 548025 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[118 (1566639230423138304)]} 0 0
   [junit4]   2> 548027 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[118 (1566639230425235456)]} 0 0
   [junit4]   2> 548029 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[119 (1566639230427332608)]} 0 0
   [junit4]   2> 548031 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[119 (1566639230429429760)]} 0 0
   [junit4]   2> 548034 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[120 (1566639230431526912)]} 0 0
   [junit4]   2> 548036 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[120 (1566639230433624064)]} 0 0
   [junit4]   2> 548038 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[121 (1566639230436769792)]} 0 0
   [junit4]   2> 548040 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[121 (1566639230438866944)]} 0 0
   [junit4]   2> 548042 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[122 (1566639230440964096)]} 0 0
   [junit4]   2> 548044 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[122 (1566639230443061248)]} 0 0
   [junit4]   2> 548046 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[123 (1566639230445158400)]} 0 0
   [junit4]   2> 548049 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[123 (1566639230447255552)]} 0 0
   [junit4]   2> 548051 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[124 (1566639230449352704)]} 0 0
   [junit4]   2> 548053 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[124 (1566639230452498432)]} 0 0
   [junit4]   2> 548055 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[125 (1566639230454595584)]} 0 0
   [junit4]   2> 548057 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[125 (1566639230456692736)]} 0 0
   [junit4]   2> 548059 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[126 (1566639230458789888)]} 0 0
   [junit4]   2> 548061 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[126 (1566639230460887040)]} 0 0
   [junit4]   2> 548063 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[127 (1566639230462984192)]} 0 0
   [junit4]   2> 548066 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[127 (1566639230465081344)]} 0 0
   [junit4]   2> 548068 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[128 (1566639230467178496)]} 0 0
   [junit4]   2> 548070 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[128 (1566639230470324224)]} 0 0
   [junit4]   2> 548072 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[129 (1566639230472421376)]} 0 0
   [junit4]   2> 548074 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[129 (1566639230474518528)]} 0 0
   [junit4]   2> 548076 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[130 (1566639230476615680)]} 0 0
   [junit4]   2> 548079 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[130 (1566639230478712832)]} 0 0
   [junit4]   2> 548081 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[131 (1566639230480809984)]} 0 0
   [junit4]   2> 548083 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[131 (1566639230482907136)]} 0 0
   [junit4]   2> 548085 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[132 (1566639230486052864)]} 0 0
   [junit4]   2> 548087 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[132 (1566639230488150016)]} 0 0
   [junit4]   2> 548089 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[133 (1566639230490247168)]} 0 0
   [junit4]   2> 548091 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[133 (1566639230492344320)]} 0 0
   [junit4]   2> 548094 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[134 (1566639230494441472)]} 0 0
   [junit4]   2> 548096 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[134 (1566639230497587200)]} 0 0
   [junit4]   2> 548098 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[135 (1566639230499684352)]} 0 0
   [junit4]   2> 548101 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[135 (1566639230501781504)]} 0 0
   [junit4]   2> 548103 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[136 (1566639230504927232)]} 0 0
   [junit4]   2> 548106 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[136 (1566639230507024384)]} 0 0
   [junit4]   2> 548108 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[137 (1566639230510170112)]} 0 0
   [junit4]   2> 548110 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[137 (1566639230512267264)]} 0 0
   [junit4]   2> 548112 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[138 (1566639230514364416)]} 0 0
   [junit4]   2> 548115 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[138 (1566639230516461568)]} 0 0
   [junit4]   2> 548117 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[139 (1566639230519607296)]} 0 0
   [junit4]   2> 548119 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[139 (1566639230521704448)]} 0 0
   [junit4]   2> 548122 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[140 (1566639230523801600)]} 0 0
   [junit4]   2> 548124 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[140 (1566639230526947328)]} 0 0
   [junit4]   2> 548127 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[141 (1566639230529044480)]} 0 0
   [junit4]   2> 548130 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[141 (1566639230532190208)]} 0 0
   [junit4]   2> 548132 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[142 (1566639230535335936)]} 0 0
   [junit4]   2> 548134 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[142 (1566639230537433088)]} 0 0
   [junit4]   2> 548137 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[143 (1566639230539530240)]} 0 0
   [junit4]   2> 548139 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[143 (1566639230541627392)]} 0 0
   [junit4]   2> 548141 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[144 (1566639230544773120)]} 0 0
   [junit4]   2> 548143 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[144 (1566639230546870272)]} 0 0
   [junit4]   2> 548146 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[145 (1566639230548967424)]} 0 0
   [junit4]   2> 548148 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[145 (1566639230552113152)]} 0 0
   [junit4]   2> 548150 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[146 (1566639230554210304)]} 0 0
   [junit4]   2> 548153 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[146 (1566639230556307456)]} 0 0
   [junit4]   2> 548155 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[147 (1566639230559453184)]} 0 0
   [junit4]   2> 548157 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[147 (1566639230561550336)]} 0 0
   [junit4]   2> 548160 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[148 (1566639230564696064)]} 0 0
   [junit4]   2> 548162 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[148 (1566639230566793216)]} 0 0
   [junit4]   2> 548165 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[149 (1566639230568890368)]} 0 0
   [junit4]   2> 548167 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={wt=javabin&version=2}{add=[149 (1566639230572036096)]} 0 0
   [junit4]   2> 548169 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 548169 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7852e53
   [junit4]   2> 548188 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5ee65b74[collection1] main]
   [junit4]   2> 548191 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 548191 INFO  
(searcherExecutor-2509-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5ee65b74[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c9)
 Uninverting(_1(6.6.0):c59)))}
   [junit4]   2> 548192 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 22
   [junit4]   2> 548194 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 548194 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@19b05dc8
   [junit4]   2> 548205 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1ab0f4b8[collection1] main]
   [junit4]   2> 548205 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 548207 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1ab0f4b8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):c9)
 Uninverting(_1(6.6.0):c59)))}
   [junit4]   2> 548208 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/o_ path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 13
   [junit4]   2> 548211 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 548211 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 548215 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 548215 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 548218 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=1
   [junit4]   2> 548384 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ 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/o_|[ff01::114]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597328&isShard=true&wt=javabin}
 hits=68 status=0 QTime=1
   [junit4]   2> 548390 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597328&ids=110,12,123,135,102,15,5,116,7,8&isShard=true&shard.url=[ff01::083]:33332/o_|[ff01::114]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548396 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=[ff01::083]:33332/o_|[ff01::114]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=172
   [junit4]   2> 548399 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548405 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597506&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548409 INFO  (qtp656208587-12307) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597506&ids=132,121,144,114,115,16,149,117,108,120&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548415 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=12
   [junit4]   2> 548417 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548423 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ 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:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597525&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548426 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597525&ids=101,134,3,114,148,17,116,139,119,10&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548431 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=10
   [junit4]   2> 548434 INFO  (qtp1562655092-12296) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548440 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ 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:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597542&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548444 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597542&ids=124,146,113,102,104,149,106,141,120,142&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548447 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 548450 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548468 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ 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:51628/o_/collection1|[ff01::083]:33332/o_|[ff01::213]:33332/o_&rows=10&version=2&q=*:*&NOW=1494063597557&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548473 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597557&ids=110,122,123,124,3,136,127,106,118,131&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_|[ff01::213]:33332/o_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548477 INFO  (qtp656208587-12307) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_|[ff01::213]:33332/o_&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=23
   [junit4]   2> 548480 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548485 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597586&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548493 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597586&ids=132,100,13,102,4,5,116,107,108,109&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548495 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=13
   [junit4]   2> 548498 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548504 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ 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:51628/o_/collection1|[ff01::114]:33332/o_&rows=10&version=2&q=*:*&NOW=1494063597605&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548507 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597605&ids=12,133,2,147,125,116,108,141,120,142&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1|[ff01::114]:33332/o_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548508 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1|[ff01::114]:33332/o_&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 548511 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548515 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ 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/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_&rows=10&version=2&q=*:*&NOW=1494063597617&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548518 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597617&ids=111,103,15,136,137,16,138,127,106,8&isShard=true&shard.url=[ff01::114]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548519 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=[ff01::114]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 548522 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548526 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ 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/o_|[ff01::114]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_&rows=10&version=2&q=*:*&NOW=1494063597628&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548529 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597628&ids=121,12,114,148,126,104,17,117,119,109&isShard=true&shard.url=[ff01::213]:33332/o_|[ff01::114]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548530 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=[ff01::213]:33332/o_|[ff01::114]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 548532 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548537 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_|[ff01::213]:33332/o_&rows=10&version=2&q=*:*&NOW=1494063597639&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548540 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597639&ids=110,112,2,102,103,128,107,8,9,142&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_|[ff01::213]:33332/o_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548541 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_|[ff01::213]:33332/o_&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 548544 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548549 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ 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:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597651&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548552 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597651&ids=111,135,15,104,16,149,128,118,9,130&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548553 INFO  (qtp656208587-12307) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 548555 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548561 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ 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:51628/o_/collection1|[ff01::213]:33332/o_|[ff01::114]:33332/o_&rows=10&version=2&q=*:*&NOW=1494063597662&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548564 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597662&ids=143,110,122,145,102,124,1001,107,119,10&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1|[ff01::213]:33332/o_|[ff01::114]:33332/o_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548565 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1|[ff01::213]:33332/o_|[ff01::114]:33332/o_&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 548568 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548573 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597675&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548576 INFO  (qtp656208587-12307) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597675&ids=1,145,102,146,124,137,106,7,107,119&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548577 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 548580 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548584 INFO  (qtp656208587-12307) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_|[ff01::114]:33332/o_&rows=10&version=2&q=*:*&NOW=1494063597686&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548586 INFO  (qtp656208587-12307) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597686&ids=133,112,134,4,104,138,139,129,130,131&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_|[ff01::114]:33332/o_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548588 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_|[ff01::114]:33332/o_&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 548590 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548594 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ 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/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597696&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548597 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597696&ids=143,111,14,136,104,8,107,9,109,10&isShard=true&shard.url=[ff01::083]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548598 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=[ff01::083]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 548601 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548605 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=[ff01::114]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_&rows=10&version=2&q=*:*&NOW=1494063597707&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548608 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597707&ids=121,133,1,13,145,134,115,126,148,108&isShard=true&shard.url=[ff01::114]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548609 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=[ff01::114]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::083]:33332/o_&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 548611 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548616 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=http://127.0.0.1:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597717&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548618 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597717&ids=132,110,12,123,135,102,114,147,136,127&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548619 INFO  (qtp656208587-12307) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 548621 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548626 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=[ff01::114]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597728&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548629 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597728&ids=121,1,112,103,126,138,6,129,107,120&isShard=true&shard.url=[ff01::114]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548630 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=[ff01::114]:33332/o_|[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 548632 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=a_i1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 548637 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=http://127.0.0.1:51628/o_/collection1|[ff01::213]:33332/o_|[ff01::114]:33332/o_&rows=10&version=2&q=*:*&NOW=1494063597739&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548639 INFO  (qtp656208587-12307) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597739&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:51628/o_/collection1|[ff01::213]:33332/o_|[ff01::114]:33332/o_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548640 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=http://127.0.0.1:51628/o_/collection1|[ff01::213]:33332/o_|[ff01::114]:33332/o_&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 548644 INFO  (qtp1562655092-12294) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 548649 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&shard.url=[ff01::083]:33332/o_|[ff01::114]:33332/o_|http://127.0.0.1:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597750&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548662 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597750&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::083]:33332/o_|[ff01::114]:33332/o_|http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=11
   [junit4]   2> 548663 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=[ff01::083]:33332/o_|[ff01::114]:33332/o_|http://127.0.0.1:51628/o_/collection1&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=17
   [junit4]   2> 548669 INFO  (qtp1562655092-12295) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=a_i1[*+TO+*]&distrib=false&sort=a_i1+asc&wt=javabin&version=2} 
hits=13 status=0 QTime=3
   [junit4]   2> 548674 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc&shard.url=[ff01::083]:33332/o_|http://127.0.0.1:51628/o_/collection1&rows=10&version=2&q=a_i1[*+TO+*]&NOW=1494063597776&isShard=true&wt=javabin}
 hits=13 status=0 QTime=0
   [junit4]   2> 548678 INFO  (qtp656208587-12306) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=a_i1[*+TO+*]&df=text&distrib=false&shards.purpose=64&NOW=1494063597776&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::083]:33332/o_|http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 548679 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=a_i1[*+TO+*]&shards=[ff01::083]:33332/o_|http://127.0.0.1:51628/o_/collection1&sort=a_i1+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=7
   [junit4]   2> 548684 INFO  (qtp1562655092-12292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&sort=a_i1+asc,+id+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=2
   [junit4]   2> 548689 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc,+id+desc&shard.url=[ff01::213]:33332/o_|[ff01::114]:33332/o_|http://127.0.0.1:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597791&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548693 INFO  (qtp656208587-12309) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1494063597791&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::213]:33332/o_|[ff01::114]:33332/o_|http://127.0.0.1:51628/o_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548694 INFO  (qtp656208587-12307) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=[ff01::213]:33332/o_|[ff01::114]:33332/o_|http://127.0.0.1:51628/o_/collection1&sort=a_i1+asc,+id+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 548697 INFO  (qtp1562655092-12293) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 548702 INFO  (qtp656208587-12312) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::114]:33332/o_&rows=10&version=2&q=*:*&NOW=1494063597803&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548705 INFO  (qtp656208587-12311) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&fl=*,score&shards.purpose=64&NOW=1494063597803&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::114]:33332/o_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 548706 INFO  (qtp656208587-12310) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&shards=[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1|[ff01::114]:33332/o_&fl=*,score&sort=a_i1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 548709 INFO  (qtp1562655092-12289) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 548713 INFO  (qtp656208587-12313) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1&rows=10&version=2&q=*:*&NOW=1494063597815&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 548716 INFO  (qtp656208587-12307) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={q=*:*&df=text&distrib=false&fl=*,score&shards.purpose=64&NOW=1494063597815&ids=133,112,134,4,104,138,139,129,130,131&isShard=true&shard.url=[ff01::213]:33332/o_|http://127.0.0.1:51628/o_/collection1&wt=javabin&versio

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

:[1+TO+50]&q=*:*&shards.tolerant=true&NOW=1494063675662&ids=11,101,13,2,15,4,17,6,1001,8&isShard=true&facet=false&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 626566 INFO  (qtp1743521391-12356) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={facet.query=a_i1:[1+TO+50]&facet.query=a_i1:[1+TO+50]&q=*:*&shards=[ff01::114]:33332/o_|[ff01::213]:33332/o_|[ff01::083]:33332/o_|http://127.0.0.1:45802/o_/collection1,http://127.0.0.1:33948/o_/collection1|[ff01::213]:33332/o_|[ff01::114]:33332/o_&shards.tolerant=true&shards.info=true&facet=true&wt=javabin&version=2}
 hits=70 status=0 QTime=8
   [junit4]   2> 626595 WARN  (qtp71128018-12341) [    x:collection1] 
o.a.s.h.c.SearchHandler Query: 
distrib=false&fl=id,a_i1&group.limit=10&sort=a_i1+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;
 The request took too long to iterate over terms. Timeout: timeoutAt: 
934148130405166 (System.nanoTime(): 934148152459588), 
TermsEnum=AssertingTermsEnum(org.apache.lucene.codecs.blocktree.SegmentTermsEnum@3d12f847)
   [junit4]   2> 626597 INFO  (qtp71128018-12341) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={distrib=false&fl=id,a_i1&group.limit=10&sort=a_i1+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=28
   [junit4]   2> 626610 WARN  (qtp1743521391-12351) [    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_i1+asc,+id+asc&shards.info=true&shard.url=[ff01::114]:33332/o_|[ff01::213]:33332/o_|[ff01::083]:33332/o_|http://127.0.0.1:45802/o_/collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&group.distributed.second=true&q=*:*&shards.tolerant=true&NOW=1494063675703&isShard=true&timeAllowed=1&wt=javabin&group=true;
 The request took too long to iterate over terms. Timeout: timeoutAt: 
934148165312660 (System.nanoTime(): 934148167675164), 
TermsEnum=AssertingTermsEnum(org.apache.lucene.codecs.blocktree.SegmentTermsEnum@74d39796)
   [junit4]   2> 626612 INFO  (qtp1743521391-12351) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={df=text&distrib=false&fl=id&group.limit=10&shards.purpose=4&start=0&sort=a_i1+asc,+id+asc&shards.info=true&shard.url=[ff01::114]:33332/o_|[ff01::213]:33332/o_|[ff01::083]:33332/o_|http://127.0.0.1:45802/o_/collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&group.distributed.second=true&q=*:*&shards.tolerant=true&NOW=1494063675703&isShard=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=5
   [junit4]   2> 626626 INFO  (qtp1743521391-12357) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/o_ path=/select 
params={fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=[ff01::114]:33332/o_|[ff01::213]:33332/o_|[ff01::083]:33332/o_|http://127.0.0.1:45802/o_/collection1,[ff01::213]:33332/o_|http://127.0.0.1:33948/o_/collection1&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=27
   [junit4]   2> 626629 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 626635 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@26830897{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 626635 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=279293854
   [junit4]   2> 626643 INFO  (coreCloseExecutor-2562-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@70f4caa
   [junit4]   2> 626735 INFO  (coreCloseExecutor-2562-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 626735 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 626736 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@363bf09d{/o_,null,UNAVAILABLE}
   [junit4]   2> 626755 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@34fe4ce{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 626755 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=545742985
   [junit4]   2> 626783 INFO  (coreCloseExecutor-2564-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@39fc9a1d
   [junit4]   2> 626879 INFO  (coreCloseExecutor-2564-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 626881 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 626881 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7d6c800b{/o_,null,UNAVAILABLE}
   [junit4]   2> 626888 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@45d75d2a{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 626888 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1352450025
   [junit4]   2> 626926 INFO  (coreCloseExecutor-2566-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7830b32f
   [junit4]   2> 627006 INFO  (coreCloseExecutor-2566-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 627007 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 627011 INFO  
(TEST-TestDistributedSearch.test-seed#[7D611F00C2B01A8E]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@3a04c619{/o_,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=7D611F00C2B01A8E -Dtests.multiplier=2 -Dtests.slow=true 
-Dtests.locale=fr-BE -Dtests.timezone=America/Nome -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] FAILURE 81.0s J0 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected to find 
shardAddress in the up shard info
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([7D611F00C2B01A8E:F53520DA6C4C7776]: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:745)
   [junit4]   2> 627016 INFO  
(SUITE-TestDistributedSearch-seed#[7D611F00C2B01A8E]-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_7D611F00C2B01A8E-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62), 
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=fr-BE, 
timezone=America/Nome
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_121 (64-bit)/cpus=4,threads=1,free=222729416,total=530579456
   [junit4]   2> NOTE: All tests run in this JVM: [TestIntervalFaceting, 
CurrencyFieldOpenExchangeTest, BlockJoinFacetDistribTest, 
ExternalFileFieldSortTest, HdfsUnloadDistributedZkTest, 
BlobRepositoryCloudTest, HLLSerializationTest, TestCollationFieldDocValues, 
TestCopyFieldCollectionResource, TestFieldCacheReopen, 
TestImpersonationWithHadoopAuth, TestAuthenticationFramework, 
TestRandomDVFaceting, FacetPivotSmallTest, TestReloadAndDeleteDocs, 
TestNoOpRegenerator, TestCloudRecovery, TestLRUStatsCache, 
SolrRequestParserTest, TestCrossCoreJoin, BasicZkTest, 
NotRequiredUniqueKeyTest, TestSQLHandler, TestHdfsUpdateLog, TestLazyCores, 
TestDocumentBuilder, CacheHeaderTest, PathHierarchyTokenizerFactoryTest, 
DisMaxRequestHandlerTest, TestSubQueryTransformerDistrib, 
PreAnalyzedFieldManagedSchemaCloudTest, SolrIndexMetricsTest, 
ConfigSetsAPITest, DocValuesMissingTest, TestSolrConfigHandler, 
DistributedFacetPivotSmallAdvancedTest, TestPerFieldSimilarity, 
LeaderElectionContextKeyTest, HdfsLockFactoryTest, SolrGraphiteReporterTest, 
TestSolrFieldCacheMBean, TestLeaderElectionWithEmptyReplica, QueryEqualityTest, 
TestClusterStateMutator, RegexBoostProcessorTest, TestDownShardTolerantSearch, 
TestConfigSetProperties, PeerSyncTest, WrapperMergePolicyFactoryTest, 
CollectionsAPIAsyncDistributedZkTest, TestReplicaProperties, TestPathTrie, 
TestCryptoKeys, TestValueSourceCache, TestStressLiveNodes, DeleteStatusTest, 
OverseerCollectionConfigSetProcessorTest, DistanceUnitsTest, 
TestSSLRandomization, TestTrieFacet, TestDistributedSearch]
   [junit4] Completed [212/711 (1!)] on J0 in 80.97s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 45074 lines...]

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