[JENKINS] Lucene-Solr-6.x-Linux (32bit/jdk1.8.0_121) - Build # 3099 - Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/3099/
Java: 32bit/jdk1.8.0_121 -server -XX:+UseSerialGC

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([6A9C571829B52D8:8EFDFAAB2C673F20]: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 11237 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001/init-core-data-001
   [junit4]   2> 115373 INFO  
(SUITE-TestDistributedSearch-seed#[6A9C571829B52D8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields
   [junit4]   2> 115374 INFO  
(SUITE-TestDistributedSearch-seed#[6A9C571829B52D8]-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> 115374 INFO  
(SUITE-TestDistributedSearch-seed#[6A9C571829B52D8]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /qf_/
   [junit4]   2> 115509 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001/tempDir-001/control/cores/collection1
   [junit4]   2> 115510 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 115511 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@d5d0fa{/qf_,null,AVAILABLE}
   [junit4]   2> 115514 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@a7c2e9{HTTP/1.1,[http/1.1]}{127.0.0.1:37332}
   [junit4]   2> 115514 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] o.e.j.s.Server 
Started @117357ms
   [junit4]   2> 115514 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/qf_, 
hostPort=37332, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001/tempDir-001/control/cores}
   [junit4]   2> 115514 ERROR 
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 115514 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.0
   [junit4]   2> 115514 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 115514 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 115514 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-19T18:12:01.374Z
   [junit4]   2> 115515 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001/tempDir-001/control/solr.xml
   [junit4]   2> 115532 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 115551 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001/tempDir-001/control/cores
   [junit4]   2> 115551 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 115551 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 115594 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 115630 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 115718 WARN  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema 
is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 115726 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 115733 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 115733 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 115734 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 115734 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@96edff
   [junit4]   2> 115735 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=46, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=92.4423828125, 
floorSegmentMB=0.9833984375, forceMergeDeletesPctAllowed=24.61862004745661, 
segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 115777 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 115777 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 115777 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 115777 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 115778 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=29, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=64.8818359375, 
floorSegmentMB=0.921875, forceMergeDeletesPctAllowed=18.78201236901167, 
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 115779 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@74a7f4[collection1] 
main]
   [junit4]   2> 115779 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 115818 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 115985 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 115985 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 115986 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 115986 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 115986 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 115986 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 115986 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 115986 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 115987 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 115987 INFO  (coreLoadExecutor-763-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1562322793237839872
   [junit4]   2> 115987 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 115988 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@74a7f4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 116193 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 116194 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 116195 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@19dd0d6{/qf_,null,AVAILABLE}
   [junit4]   2> 116195 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1ae9505{HTTP/1.1,[http/1.1]}{127.0.0.1:46511}
   [junit4]   2> 116195 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] o.e.j.s.Server 
Started @118038ms
   [junit4]   2> 116195 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/qf_, 
hostPort=46511, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001/tempDir-001/shard0/cores}
   [junit4]   2> 116196 ERROR 
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 116196 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.6.0
   [junit4]   2> 116196 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 116196 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 116196 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-03-19T18:12:02.056Z
   [junit4]   2> 116196 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 116201 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 116229 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001/tempDir-001/shard0/cores
   [junit4]   2> 116229 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 116229 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 116253 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
   [junit4]   2> 116282 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 116349 WARN  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema 
is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 116351 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 116357 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 116357 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 116357 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 116357 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@96edff
   [junit4]   2> 116359 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=46, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=92.4423828125, 
floorSegmentMB=0.9833984375, forceMergeDeletesPctAllowed=24.61862004745661, 
segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 116401 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 116401 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 116401 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 116401 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 116402 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=29, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=64.8818359375, 
floorSegmentMB=0.921875, forceMergeDeletesPctAllowed=18.78201236901167, 
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 116403 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1def81e[collection1] 
main]
   [junit4]   2> 116404 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 116423 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 116558 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 116558 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 116558 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 116558 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 116558 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 116558 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 116558 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 116558 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 116559 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 116559 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 116560 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1def81e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 116560 INFO  (coreLoadExecutor-774-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1562322793838673920
   [junit4]   2> 116779 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 116782 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1562322794070409216)} 0 1
   [junit4]   2> 116785 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1562322794073554944)} 0 1
   [junit4]   2> 116796 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[1 (1562322794077749248)]} 0 8
   [junit4]   2> 116800 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[1 (1562322794087186432)]} 0 2
   [junit4]   2> 116803 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[2 (1562322794092429312)]} 0 1
   [junit4]   2> 116806 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[2 (1562322794095575040)]} 0 1
   [junit4]   2> 116808 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[3 (1562322794098720768)]} 0 0
   [junit4]   2> 116811 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[3 (1562322794100817920)]} 0 1
   [junit4]   2> 116813 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[4 (1562322794103963648)]} 0 0
   [junit4]   2> 116816 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[4 (1562322794106060800)]} 0 1
   [junit4]   2> 116819 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[5 (1562322794109206528)]} 0 0
   [junit4]   2> 116821 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[5 (1562322794111303680)]} 0 0
   [junit4]   2> 116824 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[6 (1562322794113400832)]} 0 1
   [junit4]   2> 116826 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[6 (1562322794116546560)]} 0 0
   [junit4]   2> 116828 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[7 (1562322794119692288)]} 0 0
   [junit4]   2> 116831 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[7 (1562322794121789440)]} 0 0
   [junit4]   2> 116833 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[8 (1562322794123886592)]} 0 0
   [junit4]   2> 116836 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[8 (1562322794127032320)]} 0 0
   [junit4]   2> 116839 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[9 (1562322794130178048)]} 0 0
   [junit4]   2> 116841 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[9 (1562322794132275200)]} 0 0
   [junit4]   2> 116847 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 116847 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@14d78fa
   [junit4]   2> 116865 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@f9212[collection1] main]
   [junit4]   2> 116865 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 116880 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@f9212[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C9)))}
   [junit4]   2> 116881 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 33
   [junit4]   2> 116883 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 116883 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@195f1f1
   [junit4]   2> 116901 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@19951ae[collection1] main]
   [junit4]   2> 116902 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 116903 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@19951ae[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C9)))}
   [junit4]   2> 116903 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 20
   [junit4]   2> 116909 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[10 (1562322794200432640)]} 0 3
   [junit4]   2> 116913 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[10 (1562322794206724096)]} 0 2
   [junit4]   2> 116916 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[11 (1562322794210918400)]} 0 1
   [junit4]   2> 116919 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[11 (1562322794214064128)]} 0 1
   [junit4]   2> 116922 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[12 (1562322794217209856)]} 0 1
   [junit4]   2> 116924 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[12 (1562322794219307008)]} 0 0
   [junit4]   2> 116926 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[13 (1562322794222452736)]} 0 0
   [junit4]   2> 116929 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[13 (1562322794224549888)]} 0 1
   [junit4]   2> 116932 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[1001 (1562322794227695616)]} 0 0
   [junit4]   2> 116934 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[1001 (1562322794230841344)]} 0 0
   [junit4]   2> 116937 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[14 (1562322794232938496)]} 0 0
   [junit4]   2> 116939 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[14 (1562322794235035648)]} 0 0
   [junit4]   2> 116941 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[15 (1562322794237132800)]} 0 0
   [junit4]   2> 116943 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[15 (1562322794239229952)]} 0 1
   [junit4]   2> 116946 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[16 (1562322794242375680)]} 0 0
   [junit4]   2> 116948 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[16 (1562322794244472832)]} 0 0
   [junit4]   2> 116951 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[17 (1562322794247618560)]} 0 1
   [junit4]   2> 116953 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[17 (1562322794249715712)]} 0 0
   [junit4]   2> 116955 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[100 (1562322794252861440)]} 0 0
   [junit4]   2> 116957 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[100 (1562322794254958592)]} 0 0
   [junit4]   2> 116959 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[101 (1562322794257055744)]} 0 0
   [junit4]   2> 116962 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[101 (1562322794259152896)]} 0 0
   [junit4]   2> 116964 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[102 (1562322794261250048)]} 0 0
   [junit4]   2> 116966 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[102 (1562322794263347200)]} 0 0
   [junit4]   2> 116968 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[103 (1562322794266492928)]} 0 0
   [junit4]   2> 116970 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[103 (1562322794268590080)]} 0 0
   [junit4]   2> 116972 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[104 (1562322794269638656)]} 0 0
   [junit4]   2> 116974 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[104 (1562322794271735808)]} 0 0
   [junit4]   2> 116976 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[105 (1562322794274881536)]} 0 0
   [junit4]   2> 116978 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[105 (1562322794276978688)]} 0 0
   [junit4]   2> 116980 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[106 (1562322794278027264)]} 0 0
   [junit4]   2> 116982 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[106 (1562322794280124416)]} 0 0
   [junit4]   2> 116984 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[107 (1562322794283270144)]} 0 0
   [junit4]   2> 116986 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[107 (1562322794285367296)]} 0 0
   [junit4]   2> 116988 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[108 (1562322794287464448)]} 0 0
   [junit4]   2> 116991 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[108 (1562322794289561600)]} 0 1
   [junit4]   2> 116993 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[109 (1562322794292707328)]} 0 0
   [junit4]   2> 116995 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[109 (1562322794294804480)]} 0 0
   [junit4]   2> 116997 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[110 (1562322794296901632)]} 0 0
   [junit4]   2> 116999 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[110 (1562322794298998784)]} 0 0
   [junit4]   2> 117002 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[111 (1562322794301095936)]} 0 0
   [junit4]   2> 117004 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[111 (1562322794303193088)]} 0 0
   [junit4]   2> 117006 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[112 (1562322794306338816)]} 0 0
   [junit4]   2> 117008 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[112 (1562322794308435968)]} 0 0
   [junit4]   2> 117011 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[113 (1562322794310533120)]} 0 0
   [junit4]   2> 117013 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[113 (1562322794312630272)]} 0 0
   [junit4]   2> 117016 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[114 (1562322794315776000)]} 0 0
   [junit4]   2> 117018 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[114 (1562322794317873152)]} 0 0
   [junit4]   2> 117020 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[115 (1562322794319970304)]} 0 0
   [junit4]   2> 117022 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[115 (1562322794322067456)]} 0 0
   [junit4]   2> 117024 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[116 (1562322794325213184)]} 0 0
   [junit4]   2> 117026 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[116 (1562322794327310336)]} 0 0
   [junit4]   2> 117029 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[117 (1562322794330456064)]} 0 0
   [junit4]   2> 117032 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[117 (1562322794332553216)]} 0 0
   [junit4]   2> 117034 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[118 (1562322794335698944)]} 0 0
   [junit4]   2> 117037 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[118 (1562322794338844672)]} 0 0
   [junit4]   2> 117040 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[119 (1562322794341990400)]} 0 0
   [junit4]   2> 117043 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[119 (1562322794345136128)]} 0 1
   [junit4]   2> 117046 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[120 (1562322794347233280)]} 0 0
   [junit4]   2> 117049 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[120 (1562322794350379008)]} 0 0
   [junit4]   2> 117052 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[121 (1562322794353524736)]} 0 0
   [junit4]   2> 117054 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[121 (1562322794356670464)]} 0 0
   [junit4]   2> 117057 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[122 (1562322794358767616)]} 0 0
   [junit4]   2> 117060 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[122 (1562322794361913344)]} 0 0
   [junit4]   2> 117067 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[123 (1562322794369253376)]} 0 1
   [junit4]   2> 117070 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[123 (1562322794372399104)]} 0 0
   [junit4]   2> 117073 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[124 (1562322794375544832)]} 0 1
   [junit4]   2> 117076 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[124 (1562322794379739136)]} 0 0
   [junit4]   2> 117079 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[125 (1562322794382884864)]} 0 0
   [junit4]   2> 117082 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[125 (1562322794384982016)]} 0 0
   [junit4]   2> 117086 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[126 (1562322794389176320)]} 0 1
   [junit4]   2> 117089 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[126 (1562322794392322048)]} 0 1
   [junit4]   2> 117092 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[127 (1562322794395467776)]} 0 1
   [junit4]   2> 117096 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[127 (1562322794399662080)]} 0 1
   [junit4]   2> 117099 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[128 (1562322794402807808)]} 0 1
   [junit4]   2> 117103 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[128 (1562322794407002112)]} 0 1
   [junit4]   2> 117106 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[129 (1562322794411196416)]} 0 0
   [junit4]   2> 117109 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[129 (1562322794414342144)]} 0 0
   [junit4]   2> 117112 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[130 (1562322794417487872)]} 0 0
   [junit4]   2> 117116 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[130 (1562322794420633600)]} 0 1
   [junit4]   2> 117119 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[131 (1562322794423779328)]} 0 1
   [junit4]   2> 117122 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[131 (1562322794426925056)]} 0 0
   [junit4]   2> 117124 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[132 (1562322794430070784)]} 0 0
   [junit4]   2> 117129 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[132 (1562322794434265088)]} 0 1
   [junit4]   2> 117140 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[133 (1562322794445799424)]} 0 1
   [junit4]   2> 117143 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[133 (1562322794448945152)]} 0 0
   [junit4]   2> 117145 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[134 (1562322794452090880)]} 0 0
   [junit4]   2> 117156 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[134 (1562322794462576640)]} 0 1
   [junit4]   2> 117159 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[135 (1562322794465722368)]} 0 1
   [junit4]   2> 117162 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[135 (1562322794468868096)]} 0 0
   [junit4]   2> 117164 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[136 (1562322794472013824)]} 0 0
   [junit4]   2> 117174 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[136 (1562322794474110976)]} 0 8
   [junit4]   2> 117180 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[137 (1562322794487742464)]} 0 1
   [junit4]   2> 117184 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[137 (1562322794490888192)]} 0 1
   [junit4]   2> 117187 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[138 (1562322794495082496)]} 0 1
   [junit4]   2> 117190 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[138 (1562322794498228224)]} 0 1
   [junit4]   2> 117193 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[139 (1562322794501373952)]} 0 1
   [junit4]   2> 117197 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[139 (1562322794505568256)]} 0 1
   [junit4]   2> 117199 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[140 (1562322794508713984)]} 0 0
   [junit4]   2> 117201 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[140 (1562322794510811136)]} 0 0
   [junit4]   2> 117204 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[141 (1562322794512908288)]} 0 0
   [junit4]   2> 117208 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[141 (1562322794516054016)]} 0 2
   [junit4]   2> 117211 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[142 (1562322794520248320)]} 0 0
   [junit4]   2> 117213 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[142 (1562322794522345472)]} 0 0
   [junit4]   2> 117216 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[143 (1562322794525491200)]} 0 0
   [junit4]   2> 117221 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[143 (1562322794530734080)]} 0 1
   [junit4]   2> 117224 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[144 (1562322794533879808)]} 0 1
   [junit4]   2> 117227 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[144 (1562322794537025536)]} 0 0
   [junit4]   2> 117230 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[145 (1562322794540171264)]} 0 1
   [junit4]   2> 117234 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[145 (1562322794544365568)]} 0 0
   [junit4]   2> 117237 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[146 (1562322794547511296)]} 0 0
   [junit4]   2> 117245 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[146 (1562322794555899904)]} 0 1
   [junit4]   2> 117247 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[147 (1562322794559045632)]} 0 0
   [junit4]   2> 117249 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[147 (1562322794561142784)]} 0 0
   [junit4]   2> 117252 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[148 (1562322794563239936)]} 0 0
   [junit4]   2> 117256 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[148 (1562322794567434240)]} 0 2
   [junit4]   2> 117259 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[149 (1562322794571628544)]} 0 0
   [junit4]   2> 117261 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={wt=javabin&version=2}{add=[149 (1562322794573725696)]} 0 0
   [junit4]   2> 117263 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 117263 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@14d78fa
   [junit4]   2> 117300 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7bebbe[collection1] main]
   [junit4]   2> 117301 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 117302 INFO  
(searcherExecutor-764-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7bebbe[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C9)
 Uninverting(_1(6.6.0):C59)))}
   [junit4]   2> 117303 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 40
   [junit4]   2> 117305 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 117306 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@195f1f1
   [junit4]   2> 117329 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1980074[collection1] main]
   [junit4]   2> 117332 INFO  
(searcherExecutor-775-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1980074[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C9)
 Uninverting(_1(6.6.0):C59)))}
   [junit4]   2> 117333 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 117333 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/qf_ path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 27
   [junit4]   2> 117336 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 117336 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 117339 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 117339 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=1
   [junit4]   2> 117342 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=1
   [junit4]   2> 117368 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&rows=10&version=2&q=*:*&NOW=1489947123211&isShard=true&wt=javabin}
 hits=68 status=0 QTime=1
   [junit4]   2> 117374 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123211&ids=121,1,100,137,149,105,7,117,129,107&isShard=true&shard.url=[ff01::114]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 117378 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=[ff01::114]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=26
   [junit4]   2> 117382 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117400 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_|[ff01::083]:33332/qf_|[ff01::213]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123251&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117415 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123251&ids=11,132,133,2,3,146,15,104,127,108&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_|[ff01::083]:33332/qf_|[ff01::213]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 117419 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_|[ff01::083]:33332/qf_|[ff01::213]:33332/qf_&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=28
   [junit4]   2> 117424 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=2
   [junit4]   2> 117436 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::213]:33332/qf_|[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&rows=10&version=2&q=*:*&NOW=1489947123288&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117440 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123288&ids=132,133,100,124,3,147,103,139,1001,131&isShard=true&shard.url=[ff01::213]:33332/qf_|[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 117443 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=[ff01::213]:33332/qf_|[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=15
   [junit4]   2> 117454 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=7
   [junit4]   2> 117467 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&rows=10&version=2&q=*:*&NOW=1489947123319&isShard=true&wt=javabin}
 hits=68 status=0 QTime=5
   [junit4]   2> 117472 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123319&ids=12,111,14,102,136,137,149,118,119,142&isShard=true&shard.url=[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 117474 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=15
   [junit4]   2> 117478 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117484 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123341&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117489 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123341&ids=132,144,13,123,2,146,103,139,129,119&isShard=true&shard.url=[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 117490 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 117493 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117499 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123357&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117503 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123357&ids=11,134,101,124,15,147,115,128,1001,120&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117504 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 117508 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117514 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_|[ff01::213]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123371&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117518 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123371&ids=121,143,11,111,149,6,139,8,118,120&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_|[ff01::213]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 117520 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_|[ff01::213]:33332/qf_&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 117544 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117550 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123407&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117554 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123407&ids=2,101,145,135,4,114,137,128,10,142&isShard=true&shard.url=[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117556 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 117561 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=2
   [junit4]   2> 117577 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123431&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117580 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123431&ids=100,135,125,149,17,128,1001,7,8,119&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117582 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=11
   [junit4]   2> 117585 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117591 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_|[ff01::083]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123448&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117594 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123448&ids=11,1,134,124,102,4,104,6,117,106&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_|[ff01::083]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117596 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_|[ff01::083]:33332/qf_&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 117599 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117604 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:46511/qf_/collection1&rows=10&version=2&q=*:*&NOW=1489947123462&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117607 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123462&ids=144,133,146,136,4,126,5,105,139,142&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117608 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 117611 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117616 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_|[ff01::213]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123474&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117619 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123474&ids=121,111,122,101,7,128,107,140,130,10&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_|[ff01::213]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117621 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_|[ff01::213]:33332/qf_&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 117624 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117630 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_|[ff01::083]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123487&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117632 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123487&ids=143,133,101,146,15,115,6,129,8,141&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_|[ff01::083]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117633 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_|[ff01::083]:33332/qf_&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 117637 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117642 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::213]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123500&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117645 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123500&ids=11,100,111,135,103,138,17,128,118,107&isShard=true&shard.url=[ff01::213]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117646 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=[ff01::213]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 117648 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117653 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::083]:33332/qf_|[ff01::114]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&rows=10&version=2&q=*:*&NOW=1489947123510&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117656 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123510&ids=143,121,145,113,102,124,14,1001,109,142&isShard=true&shard.url=[ff01::083]:33332/qf_|[ff01::114]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117657 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=[ff01::083]:33332/qf_|[ff01::114]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 117659 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117664 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::083]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123521&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117666 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123521&ids=110,101,147,114,137,126,138,117,9,119&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::083]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117667 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1|[ff01::213]:33332/qf_|[ff01::083]:33332/qf_&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 117670 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117674 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123532&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117677 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123532&ids=143,122,111,2,134,113,148,116,17,130&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117678 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 117681 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117685 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123543&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117688 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123543&ids=123,101,14,136,114,149,6,118,119,141&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117689 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1|[ff01::114]:33332/qf_&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 117695 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=a_i_ni_p+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117739 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_ni_p+desc&shard.url=http://127.0.0.1:46511/qf_/collection1&rows=10&version=2&q=*:*&NOW=1489947123597&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117747 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123597&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1&wt=javabin&version=2}
 status=0 QTime=5
   [junit4]   2> 117748 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1&sort=a_i_ni_p+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=11
   [junit4]   2> 117756 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i_ni_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 117762 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i_ni_p,5))+desc&shard.url=http://127.0.0.1:46511/qf_/collection1&rows=10&version=2&q=*:*&NOW=1489947123619&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117764 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123619&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117766 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=http://127.0.0.1:46511/qf_/collection1&sort={!func}testfunc(add(a_i_ni_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 117776 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=a_i_ni_p[*+TO+*]&distrib=false&sort=a_i_ni_p+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=7
   [junit4]   2> 117781 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_ni_p+asc&shard.url=http://127.0.0.1:46511/qf_/collection1&rows=10&version=2&q=a_i_ni_p[*+TO+*]&NOW=1489947123638&isShard=true&wt=javabin}
 hits=13 status=0 QTime=0
   [junit4]   2> 117783 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=a_i_ni_p[*+TO+*]&distrib=false&shards.purpose=64&NOW=1489947123638&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=http://127.0.0.1:46511/qf_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117784 INFO  (qtp28357942-1688) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=a_i_ni_p[*+TO+*]&shards=http://127.0.0.1:46511/qf_/collection1&sort=a_i_ni_p+asc&wt=javabin&version=2}
 hits=13 status=0 QTime=5
   [junit4]   2> 117787 INFO  (qtp11207017-1666) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=a_i_ni_p+asc,+id+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=1
   [junit4]   2> 117793 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_ni_p+asc,+id+desc&shard.url=[ff01::213]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_|[ff01::114]:33332/qf_&rows=10&version=2&q=*:*&NOW=1489947123650&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117796 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&shards.purpose=64&NOW=1489947123650&ids=11,144,145,146,147,4,148,149,6,1001&isShard=true&shard.url=[ff01::213]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_|[ff01::114]:33332/qf_&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117797 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=[ff01::213]:33332/qf_|http://127.0.0.1:46511/qf_/collection1|[ff01::083]:33332/qf_|[ff01::114]:33332/qf_&sort=a_i_ni_p+asc,+id+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 117799 INFO  (qtp11207017-1672) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=a_i_ni_p+desc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 117804 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i_ni_p+desc&shard.url=[ff01::213]:33332/qf_|[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&rows=10&version=2&q=*:*&NOW=1489947123662&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117807 INFO  (qtp28357942-1690) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1489947123662&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::213]:33332/qf_|[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117808 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=[ff01::213]:33332/qf_|[ff01::083]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&fl=*,score&sort=a_i_ni_p+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 117810 INFO  (qtp11207017-1673) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} 
hits=68 status=0 QTime=0
   [junit4]   2> 117814 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::114]:33332/qf_|[ff01::213]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&rows=10&version=2&q=*:*&NOW=1489947123672&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 117817 INFO  (qtp28357942-1689) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1489947123672&ids=11,100,111,135,103,138,17,128,118,107&isShard=true&shard.url=[ff01::114]:33332/qf_|[ff01::213]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 117818 INFO  (qtp28357942-1684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&shards=[ff01::114]:33332/qf_|[ff01::213]:33332/qf_|http://127.0.0.1:46511/qf_/collection1&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 117820 INFO  (qtp11207017-1671) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 117825 INFO  (qtp28357942-1683) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&sh

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

lection1] o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&shards.purpose=64&shards.info=true&shard.url=http://127.0.0.1:34923/qf_/collection1&version=2&facet.query=a_i_ni_p:[1+TO+50]&q=*:*&shards.tolerant=true&NOW=1489947227628&ids=1,13,102,4,16,105,1001,7,108,10&isShard=true&facet=false&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 221777 INFO  (qtp5993774-1822) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={facet.query=a_i_ni_p:[1+TO+50]&facet.query=a_i_ni_p:[1+TO+50]&q=*:*&shards=http://127.0.0.1:34923/qf_/collection1,http://127.0.0.1:42317/qf_/collection1,http://127.0.0.1:38265/qf_/collection1|[ff01::083]:33332/qf_&shards.tolerant=true&shards.info=true&facet=true&wt=javabin&version=2}
 hits=48 status=0 QTime=8
   [junit4]   2> 221780 INFO  (qtp30022924-1788) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id,a_i_ni_p&group.limit=10&sort=a_i_ni_p+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 hits=69 status=0 QTime=0
   [junit4]   2> 221790 INFO  (qtp5993774-1816) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id&group.limit=10&shards.purpose=4&start=0&sort=a_i_ni_p+asc,+id+asc&shards.info=true&shard.url=http://127.0.0.1:42317/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&group.distributed.second=true&q=*:*&shards.tolerant=true&NOW=1489947227644&isShard=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=0
   [junit4]   2> 221793 INFO  (qtp5993774-1821) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={distrib=false&fl=id,a_i_ni_p,id&group.limit=10&shards.purpose=64&shards.info=true&shard.url=http://127.0.0.1:42317/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&NOW=1489947227644&ids=8&isShard=true&timeAllowed=1&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 221794 INFO  (qtp27623438-1800) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/qf_ path=/select 
params={fl=id,a_i_ni_p&group.limit=10&sort=a_i_ni_p+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=[ff01::083]:33332/qf_|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_|http://127.0.0.1:34923/qf_/collection1,http://127.0.0.1:42317/qf_/collection1|[ff01::213]:33332/qf_|[ff01::114]:33332/qf_,[ff01::114]:33332/qf_|http://127.0.0.1:38265/qf_/collection1&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true}
 status=0 QTime=9
   [junit4]   2> 221794 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 221795 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@fbbe0d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 221795 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=16775988
   [junit4]   2> 221796 INFO  (coreCloseExecutor-869-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1873409
   [junit4]   2> 221865 INFO  (coreCloseExecutor-869-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 221865 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 221865 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@acea96{/qf_,null,UNAVAILABLE}
   [junit4]   2> 221866 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@17ba796{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 221867 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=12981804
   [junit4]   2> 221867 INFO  (coreCloseExecutor-871-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@8f8ad7
   [junit4]   2> 221916 INFO  (coreCloseExecutor-871-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 221917 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 221917 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6452f8{/qf_,null,UNAVAILABLE}
   [junit4]   2> 221922 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@e660a0{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 221923 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=31369311
   [junit4]   2> 221923 INFO  (coreCloseExecutor-873-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@156df87
   [junit4]   2> 221951 INFO  (coreCloseExecutor-873-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 221952 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 221952 INFO  
(TEST-TestDistributedSearch.test-seed#[6A9C571829B52D8]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@6d63c5{/qf_,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=6A9C571829B52D8 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=el-GR -Dtests.timezone=Australia/North -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE  107s J0 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: Expected to find 
shardAddress in the up shard info
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([6A9C571829B52D8:8EFDFAAB2C673F20]: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> 221958 INFO  
(SUITE-TestDistributedSearch-seed#[6A9C571829B52D8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_6A9C571829B52D8-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{severity=FST50, other_tl1=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
foo_sev_enum=PostingsFormat(name=LuceneVarGapFixedInterval), 
SubjectTerms_mfacet=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
a_n_tdt=PostingsFormat(name=LuceneVarGapFixedInterval), 
multiDefault=PostingsFormat(name=Direct), n_td1=PostingsFormat(name=Direct), 
b_n_tdt=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
n_ti1=PostingsFormat(name=LuceneVarGapFixedInterval), 
rnd_b=PostingsFormat(name=LuceneVarGapFixedInterval), 
n_tl1=PostingsFormat(name=Direct), a_s=FST50, oddField_s=FST50, 
n_tf1=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
lowerfilt1and2=PostingsFormat(name=Direct), 
a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), foo_b=FST50, 
n_tdt1=PostingsFormat(name=LuceneVarGapDocFreqInterval), 
lowerfilt=PostingsFormat(name=Direct), 
id=PostingsFormat(name=LuceneVarGapFixedInterval), text=FST50}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), 
foo_sev_enum=DocValuesFormat(name=Asserting), 
n_l1=DocValuesFormat(name=Lucene54), intDefault=DocValuesFormat(name=Lucene54), 
n_dt1=DocValuesFormat(name=Direct), n_d1=DocValuesFormat(name=Lucene54), 
range_facet_l=DocValuesFormat(name=Lucene54), 
n_f1=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Lucene54), 
foo_d=DocValuesFormat(name=Asserting), 
range_facet_i_dv=DocValuesFormat(name=Lucene54), 
foo_f=DocValuesFormat(name=Lucene54), 
intDvoDefault=DocValuesFormat(name=Lucene54), 
a_i_ni_p=DocValuesFormat(name=Direct), 
timestamp=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=1587, 
maxMBSortInHeap=6.491313081659718, 
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=el-GR, 
timezone=Australia/North
   [junit4]   2> NOTE: Linux 4.4.0-66-generic i386/Oracle Corporation 1.8.0_121 
(32-bit)/cpus=12,threads=2,free=55641944,total=171474944
   [junit4]   2> NOTE: All tests run in this JVM: [DateFieldTest, 
TestOmitPositions, DefaultValueUpdateProcessorTest, TestPseudoReturnFields, 
DocValuesNotIndexedTest, MBeansHandlerTest, TestReplicaProperties, 
TestBinaryResponseWriter, TestSolrDeletionPolicy2, TestClassNameShortening, 
DistributedSpellCheckComponentTest, AnalysisAfterCoreReloadTest, 
TestCodecSupport, TestCryptoKeys, DistribJoinFromCollectionTest, 
TestObjectReleaseTracker, TestCloudInspectUtil, TestDistributedSearch]
   [junit4] Completed [95/700 (1!)] on J0 in 106.60s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 56935 lines...]


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