You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/10/07 17:06:32 UTC
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_102) - Build #
501 - Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/501/
Java: 32bit/jdk1.8.0_102 -server -XX:+UseParallelGC
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([3EC082A62C50A54C:B694BD7C82ACC8B4]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10768 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\init-core-data-001
[junit4] 2> 149478 INFO (SUITE-TestDistributedSearch-seed#[3EC082A62C50A54C]-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> 149479 INFO (SUITE-TestDistributedSearch-seed#[3EC082A62C50A54C]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 149931 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\control\cores\collection1
[junit4] 2> 149934 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 149935 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a086ac{/,null,AVAILABLE}
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Started ServerConnector@13c0eff{HTTP/1.1,[http/1.1]}{127.0.0.1:54412}
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.Server Started @153934ms
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=54412, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\control\cores}
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 149938 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-07T16:02:15.725Z
[junit4] 2> 149940 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\control\solr.xml
[junit4] 2> 149955 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 149957 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\control\cores
[junit4] 2> 149957 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 149959 INFO (coreLoadExecutor-268-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 150011 WARN (coreLoadExecutor-268-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 150012 INFO (coreLoadExecutor-268-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 150031 INFO (coreLoadExecutor-268-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 150101 WARN (coreLoadExecutor-268-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 150103 INFO (coreLoadExecutor-268-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 150118 INFO (coreLoadExecutor-268-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 150119 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\control\cores\collection1\data\]
[junit4] 2> 150119 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1087051
[junit4] 2> 150120 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8851739321577488]
[junit4] 2> 150136 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 150136 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 150137 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 150137 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 150138 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=80.7333984375, floorSegmentMB=0.296875, forceMergeDeletesPctAllowed=6.7588909394131065, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7801593886887092
[junit4] 2> 150139 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c81c94[collection1] main]
[junit4] 2> 150139 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 150151 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 150190 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 150190 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 150192 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547547323641364480
[junit4] 2> 150192 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 150192 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 150192 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 150192 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 150194 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 150194 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 150195 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 150195 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 150195 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c81c94[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 150935 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\shard0\cores\collection1
[junit4] 2> 150938 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 150938 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f682e5{/,null,AVAILABLE}
[junit4] 2> 150939 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1c558e9{HTTP/1.1,[http/1.1]}{127.0.0.1:54415}
[junit4] 2> 150940 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.Server Started @154936ms
[junit4] 2> 150940 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=54415, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\shard0\cores}
[junit4] 2> 150941 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0
[junit4] 2> 150941 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 150941 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 150941 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-07T16:02:16.729Z
[junit4] 2> 150942 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\shard0\solr.xml
[junit4] 2> 150955 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 150959 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\shard0\cores
[junit4] 2> 150959 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 150960 INFO (coreLoadExecutor-279-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 151013 WARN (coreLoadExecutor-279-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 151014 INFO (coreLoadExecutor-279-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 151032 INFO (coreLoadExecutor-279-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 151111 WARN (coreLoadExecutor-279-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 151114 INFO (coreLoadExecutor-279-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 151130 INFO (coreLoadExecutor-279-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 151130 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\shard0\cores\collection1\data\]
[junit4] 2> 151130 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1087051
[junit4] 2> 151131 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8851739321577488]
[junit4] 2> 151151 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 151151 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 151153 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 151153 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 151154 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=80.7333984375, floorSegmentMB=0.296875, forceMergeDeletesPctAllowed=6.7588909394131065, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7801593886887092
[junit4] 2> 151154 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@15cf491[collection1] main]
[junit4] 2> 151154 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 151163 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 151201 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 151201 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 151202 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 151202 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 151202 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 151202 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547547324700426240
[junit4] 2> 151202 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 151203 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 151203 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 151204 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 151204 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 151205 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@15cf491[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 151463 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 151470 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1547547324978298880)} 0 3
[junit4] 2> 151475 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1547547324985638912)} 0 2
[junit4] 2> 151481 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1547547324989833216)]} 0 3
[junit4] 2> 151484 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1547547324994027520)]} 0 2
[junit4] 2> 151487 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1547547324998221824)]} 0 0
[junit4] 2> 151489 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1547547325000318976)]} 0 0
[junit4] 2> 151490 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1547547325002416128)]} 0 0
[junit4] 2> 151492 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1547547325004513280)]} 0 0
[junit4] 2> 151494 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1547547325006610432)]} 0 0
[junit4] 2> 151496 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1547547325008707584)]} 0 0
[junit4] 2> 151498 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1547547325010804736)]} 0 0
[junit4] 2> 151500 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1547547325012901888)]} 0 0
[junit4] 2> 151502 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1547547325014999040)]} 0 0
[junit4] 2> 151503 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1547547325016047616)]} 0 0
[junit4] 2> 151505 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1547547325018144768)]} 0 0
[junit4] 2> 151506 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1547547325019193344)]} 0 0
[junit4] 2> 151507 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1547547325020241920)]} 0 0
[junit4] 2> 151509 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1547547325022339072)]} 0 0
[junit4] 2> 151510 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1547547325023387648)]} 0 0
[junit4] 2> 151512 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1547547325025484800)]} 0 0
[junit4] 2> 151513 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 151513 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d1f526
[junit4] 2> 151528 INFO (qtp10412308-822) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@279c0d[collection1] main]
[junit4] 2> 151528 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 151529 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@279c0d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c9)))}
[junit4] 2> 151529 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 16
[junit4] 2> 151530 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 151530 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@129eb9e
[junit4] 2> 151535 INFO (qtp6321792-839) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1df51e2[collection1] main]
[junit4] 2> 151536 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 151537 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1df51e2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c9)))}
[junit4] 2> 151537 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 151542 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1547547325053796352)]} 0 3
[junit4] 2> 151547 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1547547325059039232)]} 0 2
[junit4] 2> 151548 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1547547325063233536)]} 0 0
[junit4] 2> 151550 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1547547325065330688)]} 0 0
[junit4] 2> 151553 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1547547325067427840)]} 0 0
[junit4] 2> 151555 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1547547325070573568)]} 0 0
[junit4] 2> 151557 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1547547325072670720)]} 0 0
[junit4] 2> 151558 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1547547325073719296)]} 0 0
[junit4] 2> 151561 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1547547325075816448)]} 0 0
[junit4] 2> 151562 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1547547325077913600)]} 0 0
[junit4] 2> 151564 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1547547325080010752)]} 0 0
[junit4] 2> 151566 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1547547325082107904)]} 0 0
[junit4] 2> 151567 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1547547325083156480)]} 0 0
[junit4] 2> 151568 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1547547325084205056)]} 0 0
[junit4] 2> 151570 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1547547325086302208)]} 0 0
[junit4] 2> 151571 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1547547325087350784)]} 0 0
[junit4] 2> 151572 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1547547325088399360)]} 0 0
[junit4] 2> 151573 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1547547325089447936)]} 0 0
[junit4] 2> 151574 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1547547325090496512)]} 0 0
[junit4] 2> 151575 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1547547325091545088)]} 0 0
[junit4] 2> 151576 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1547547325092593664)]} 0 0
[junit4] 2> 151578 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1547547325093642240)]} 0 0
[junit4] 2> 151579 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1547547325095739392)]} 0 0
[junit4] 2> 151580 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1547547325096787968)]} 0 0
[junit4] 2> 151581 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1547547325097836544)]} 0 0
[junit4] 2> 151582 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1547547325098885120)]} 0 0
[junit4] 2> 151583 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1547547325099933696)]} 0 0
[junit4] 2> 151584 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1547547325100982272)]} 0 0
[junit4] 2> 151585 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1547547325102030848)]} 0 0
[junit4] 2> 151586 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1547547325103079424)]} 0 0
[junit4] 2> 151587 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1547547325104128000)]} 0 0
[junit4] 2> 151588 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1547547325105176576)]} 0 0
[junit4] 2> 151590 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1547547325107273728)]} 0 0
[junit4] 2> 151591 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1547547325108322304)]} 0 0
[junit4] 2> 151593 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1547547325109370880)]} 0 0
[junit4] 2> 151594 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1547547325111468032)]} 0 0
[junit4] 2> 151595 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1547547325112516608)]} 0 0
[junit4] 2> 151596 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1547547325113565184)]} 0 0
[junit4] 2> 151597 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1547547325114613760)]} 0 0
[junit4] 2> 151598 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1547547325115662336)]} 0 0
[junit4] 2> 151599 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1547547325116710912)]} 0 0
[junit4] 2> 151600 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1547547325117759488)]} 0 0
[junit4] 2> 151602 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1547547325119856640)]} 0 0
[junit4] 2> 151603 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1547547325120905216)]} 0 0
[junit4] 2> 151604 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1547547325121953792)]} 0 0
[junit4] 2> 151605 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1547547325123002368)]} 0 0
[junit4] 2> 151606 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1547547325124050944)]} 0 0
[junit4] 2> 151607 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1547547325125099520)]} 0 0
[junit4] 2> 151609 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1547547325127196672)]} 0 0
[junit4] 2> 151610 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1547547325128245248)]} 0 0
[junit4] 2> 151612 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1547547325130342400)]} 0 0
[junit4] 2> 151613 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1547547325131390976)]} 0 0
[junit4] 2> 151614 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1547547325132439552)]} 0 0
[junit4] 2> 151615 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1547547325133488128)]} 0 0
[junit4] 2> 151616 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1547547325134536704)]} 0 0
[junit4] 2> 151618 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1547547325136633856)]} 0 0
[junit4] 2> 151619 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1547547325137682432)]} 0 0
[junit4] 2> 151620 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1547547325138731008)]} 0 0
[junit4] 2> 151621 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1547547325139779584)]} 0 0
[junit4] 2> 151623 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1547547325141876736)]} 0 0
[junit4] 2> 151624 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1547547325142925312)]} 0 0
[junit4] 2> 151626 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1547547325145022464)]} 0 0
[junit4] 2> 151627 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1547547325146071040)]} 0 0
[junit4] 2> 151628 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1547547325147119616)]} 0 0
[junit4] 2> 151629 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1547547325148168192)]} 0 0
[junit4] 2> 151630 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1547547325149216768)]} 0 0
[junit4] 2> 151631 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1547547325150265344)]} 0 0
[junit4] 2> 151632 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1547547325151313920)]} 0 0
[junit4] 2> 151634 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1547547325153411072)]} 0 0
[junit4] 2> 151636 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1547547325155508224)]} 0 0
[junit4] 2> 151638 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1547547325157605376)]} 0 0
[junit4] 2> 151639 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1547547325158653952)]} 0 0
[junit4] 2> 151640 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1547547325159702528)]} 0 0
[junit4] 2> 151641 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1547547325160751104)]} 0 0
[junit4] 2> 151642 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1547547325161799680)]} 0 0
[junit4] 2> 151643 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1547547325162848256)]} 0 0
[junit4] 2> 151645 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1547547325164945408)]} 0 0
[junit4] 2> 151646 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1547547325165993984)]} 0 0
[junit4] 2> 151647 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1547547325167042560)]} 0 0
[junit4] 2> 151648 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1547547325168091136)]} 0 0
[junit4] 2> 151649 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1547547325169139712)]} 0 0
[junit4] 2> 151650 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1547547325170188288)]} 0 0
[junit4] 2> 151652 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1547547325172285440)]} 0 0
[junit4] 2> 151653 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1547547325173334016)]} 0 0
[junit4] 2> 151654 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1547547325174382592)]} 0 0
[junit4] 2> 151655 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1547547325175431168)]} 0 0
[junit4] 2> 151656 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1547547325176479744)]} 0 0
[junit4] 2> 151658 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1547547325178576896)]} 0 0
[junit4] 2> 151659 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1547547325179625472)]} 0 0
[junit4] 2> 151660 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1547547325180674048)]} 0 0
[junit4] 2> 151663 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1547547325183819776)]} 0 0
[junit4] 2> 151664 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1547547325184868352)]} 0 0
[junit4] 2> 151665 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1547547325185916928)]} 0 0
[junit4] 2> 151666 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1547547325186965504)]} 0 0
[junit4] 2> 151667 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1547547325188014080)]} 0 0
[junit4] 2> 151668 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1547547325189062656)]} 0 0
[junit4] 2> 151669 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1547547325190111232)]} 0 0
[junit4] 2> 151670 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1547547325191159808)]} 0 0
[junit4] 2> 151671 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1547547325192208384)]} 0 0
[junit4] 2> 151672 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1547547325193256960)]} 0 0
[junit4] 2> 151673 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1547547325194305536)]} 0 0
[junit4] 2> 151674 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1547547325195354112)]} 0 0
[junit4] 2> 151675 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1547547325196402688)]} 0 0
[junit4] 2> 151676 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1547547325197451264)]} 0 0
[junit4] 2> 151678 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1547547325198499840)]} 0 0
[junit4] 2> 151679 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1547547325200596992)]} 0 0
[junit4] 2> 151680 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1547547325201645568)]} 0 0
[junit4] 2> 151681 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1547547325202694144)]} 0 0
[junit4] 2> 151683 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1547547325204791296)]} 0 0
[junit4] 2> 151684 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1547547325205839872)]} 0 0
[junit4] 2> 151685 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1547547325206888448)]} 0 0
[junit4] 2> 151686 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1547547325207937024)]} 0 0
[junit4] 2> 151688 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1547547325210034176)]} 0 0
[junit4] 2> 151689 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1547547325211082752)]} 0 0
[junit4] 2> 151690 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1547547325212131328)]} 0 0
[junit4] 2> 151691 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1547547325213179904)]} 0 0
[junit4] 2> 151693 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1547547325215277056)]} 0 0
[junit4] 2> 151695 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1547547325217374208)]} 0 0
[junit4] 2> 151697 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 151698 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d1f526
[junit4] 2> 151709 INFO (qtp10412308-819) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@453eb6[collection1] main]
[junit4] 2> 151709 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 151710 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@453eb6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c9) Uninverting(_1(6.3.0):c59)))}
[junit4] 2> 151710 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 13
[junit4] 2> 151712 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 151712 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@129eb9e
[junit4] 2> 151722 INFO (qtp6321792-837) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@37db2[collection1] main]
[junit4] 2> 151722 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 151723 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@37db2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c9) Uninverting(_1(6.3.0):c59)))}
[junit4] 2> 151723 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 11
[junit4] 2> 151725 INFO (qtp10412308-820) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 151725 INFO (qtp10412308-820) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 151727 INFO (qtp10412308-821) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 151727 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 151729 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151737 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137521&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151753 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137521&ids=11,145,135,14,115,6,117,7,9,10&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 151758 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=25
[junit4] 2> 151764 INFO (qtp10412308-823) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151770 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1475856137555&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151774 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137555&ids=122,1,123,2,15,17,105,138,129,130&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151779 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=12
[junit4] 2> 151782 INFO (qtp10412308-823) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151786 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137573&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151789 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137573&ids=12,144,1,134,14,4,104,105,149,119&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151794 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 151796 INFO (qtp10412308-824) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151800 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1475856137586&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151803 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137586&ids=122,146,136,128,129,8,108,109,141,142&isShard=true&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151805 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 151806 INFO (qtp10412308-819) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151811 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137598&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151813 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137598&ids=121,101,148,5,118,119,9,109,140,141&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151816 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:54415//collection1&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 151818 INFO (qtp10412308-820) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151822 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137608&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151824 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137608&ids=144,4,16,115,127,128,117,7,108,10&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151826 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151828 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151832 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137619&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151834 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137619&ids=121,123,134,113,104,17,149,117,106,120&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151836 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151839 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151842 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137629&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151845 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137629&ids=1,13,112,145,136,16,6,116,1001,118&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151847 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 151848 INFO (qtp10412308-817) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151851 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137638&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151853 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137638&ids=12,14,115,148,104,6,127,117,129,109&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151854 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 151857 INFO (qtp10412308-817) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151860 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1475856137647&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151863 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137647&ids=132,11,111,102,16,17,106,128,119,9&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151865 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151867 INFO (qtp10412308-824) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151871 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137658&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151873 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137658&ids=132,12,2,101,123,136,104,106,128,129&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151874 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 151876 INFO (qtp10412308-819) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151880 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137667&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151882 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137667&ids=143,133,1,113,146,102,114,138,107,141&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151883 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 151885 INFO (qtp10412308-820) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151888 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1475856137675&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151891 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137675&ids=132,11,112,135,103,147,125,127,108,130&isShard=true&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151892 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151894 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151898 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1475856137684&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151899 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137684&ids=121,100,144,124,146,5,116,117,109,10&isShard=true&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151900 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 151902 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151907 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1475856137693&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151915 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137693&ids=144,134,145,124,14,15,137,7,1001,109&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 151916 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=11
[junit4] 2> 151921 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151926 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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:54415//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1475856137711&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151928 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137711&ids=11,1,4,115,104,5,126,6,139,130&isShard=true&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151929 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1|[ff01::213]:33332/&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 151932 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151935 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137722&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151937 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137722&ids=11,121,132,122,112,5,6,105,9,109&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151937 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 151940 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151945 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1475856137731&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151948 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137731&ids=110,143,101,14,135,115,116,128,106,120&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151949 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 151952 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151957 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1475856137743&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151959 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137743&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151960 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151974 INFO (qtp10412308-820) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=11
[junit4] 2> 151977 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&shard.url=http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137764&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151978 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137764&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151979 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 151982 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=a_i1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151985 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137771&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151988 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137771&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151989 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1&sort=a_i1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151991 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151997 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1475856137781&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151998 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1475856137781&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151999 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 152001 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 152005 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= 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/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1475856137792&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 152007 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1475856137792&ids=121,100,144,124,146,5,116,117,109,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 152007 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 152010 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 152037 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137800&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 152038 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137800&ids=132,11,112,135,103,147,125,127,108,130&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 152039 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=27
[junit4] 2> 152047 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 152050 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1475856137836&start=0&fsv=true&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 152052 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&shards.purpose=64&NOW=1475856137836&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 152053 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&shards=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 152055 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&fl=*,score&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 152060 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1475856137846&start=0&fsv=true&isShard=true&shard.url=http://127.0.0.1:54415//collection1&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 152062 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&fl=*,score&shards.purpose=64&NOW=1475856137846&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 152062 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&shards=http://127.0.0.1:54415//collection1&fl=*,score&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 152066 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=quick&distrib=false&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 152072 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=quick&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1475856137856&start=0&fsv=true&isShard=true&shard.url=[ff01::213]:3
[...truncated too long message...]
it4] 2> 237682 INFO (qtp6998880-941) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id,a_i1,id&group.limit=10&shards.purpose=64&shards.info=true&shard.url=http://127.0.0.1:54648//collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&NOW=1475856223438&ids=13&isShard=true&timeAllowed=1&wt=javabin} status=0 QTime=0
[junit4] 2> 237682 INFO (qtp6362208-955) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id,a_i1,id&group.limit=10&shards.purpose=64&shards.info=true&shard.url=http://127.0.0.1:54651//collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&NOW=1475856223438&ids=8&isShard=true&timeAllowed=1&wt=javabin} status=0 QTime=0
[junit4] 2> 237689 INFO (qtp8288168-972) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=http://127.0.0.1:54648//collection1,http://127.0.0.1:54651//collection1,[ff01::213]:33332/|http://127.0.0.1:54655//collection1&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true} status=0 QTime=38
[junit4] 2> 237689 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 237691 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@b8086d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 237691 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1522571
[junit4] 2> 237692 INFO (coreCloseExecutor-373-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@149ce86
[junit4] 2> 237714 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2dd1b3{/,null,UNAVAILABLE}
[junit4] 2> 237716 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1383e09{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 237716 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=23321134
[junit4] 2> 237717 INFO (coreCloseExecutor-375-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f2ce12
[junit4] 2> 237734 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4161bb{/,null,UNAVAILABLE}
[junit4] 2> 237737 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@16324f3{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 237737 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=18592033
[junit4] 2> 237738 INFO (coreCloseExecutor-377-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1c0788f
[junit4] 2> 237752 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@9a3971{/,null,UNAVAILABLE}
[junit4] 2> 237754 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@12c5468{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 237754 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=26497352
[junit4] 2> 237756 INFO (coreCloseExecutor-379-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1396395
[junit4] 2> 237770 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1eb7ab7{/,null,UNAVAILABLE}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=3EC082A62C50A54C -Dtests.slow=true -Dtests.locale=mk -Dtests.timezone=America/Whitehorse -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 88.3s J0 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected to find shardAddress in the up shard info
[junit4] > at __randomizedtesting.SeedInfo.seed([3EC082A62C50A54C:B694BD7C82ACC8B4]:0)
[junit4] > at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 237780 INFO (SUITE-TestDistributedSearch-seed#[3EC082A62C50A54C]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001
[junit4] 2> NOTE: test params are: codec=Lucene62, sim=ClassicSimilarity, locale=mk, timezone=America/Whitehorse
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_102 (32-bit)/cpus=3,threads=2,free=70200120,total=205520896
[junit4] 2> NOTE: All tests run in this JVM: [TestPseudoReturnFields, TestStressCloudBlindAtomicUpdates, SyncSliceTest, TestGraphMLResponseWriter, HLLSerializationTest, SynonymTokenizerTest, ConnectionManagerTest, TestElisionMultitermQuery, TestMaxScoreQueryParser, TestSolrJ, HdfsWriteToMultipleCollectionsTest, ZkControllerTest, SecurityConfHandlerTest, TestStressLucene, TestDownShardTolerantSearch, TestDistributedSearch]
[junit4] Completed [36/637 (1!)] on J0 in 88.34s, 1 test, 1 failure <<< FAILURES!
[...truncated 42681 lines...]
-ecj-javadoc-lint-src:
[mkdir] Created dir: C:\Users\jenkins\AppData\Local\Temp\ecj1366159887
[ecj-lint] Compiling 127 source files to C:\Users\jenkins\AppData\Local\Temp\ecj1366159887
[ecj-lint] ----------
[ecj-lint] 1. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 21)
[ecj-lint] import java.util.Collection;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import java.util.Collection is never used
[ecj-lint] ----------
[ecj-lint] 2. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 22)
[ecj-lint] import java.util.HashSet;
[ecj-lint] ^^^^^^^^^^^^^^^^^
[ecj-lint] The import java.util.HashSet is never used
[ecj-lint] ----------
[ecj-lint] 3. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 24)
[ecj-lint] import java.util.List;
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] The import java.util.List is never used
[ecj-lint] ----------
[ecj-lint] 4. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 25)
[ecj-lint] import java.util.Set;
[ecj-lint] ^^^^^^^^^^^^^
[ecj-lint] The import java.util.Set is never used
[ecj-lint] ----------
[ecj-lint] 5. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 27)
[ecj-lint] import org.apache.lucene.analysis.Analyzer;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.analysis.Analyzer is never used
[ecj-lint] ----------
[ecj-lint] 6. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 33)
[ecj-lint] import org.apache.lucene.index.IndexableField;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.index.IndexableField is never used
[ecj-lint] ----------
[ecj-lint] 6 problems (6 errors)
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:765: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:101: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build.xml:204: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2177: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:1992: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2031: Compile failed; see the compiler error output for details.
Total time: 86 minutes 6 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_102) - Build #
502 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/502/
Java: 32bit/jdk1.8.0_102 -client -XX:+UseParallelGC
All tests passed
Build Log:
[...truncated 53455 lines...]
-ecj-javadoc-lint-src:
[mkdir] Created dir: C:\Users\jenkins\AppData\Local\Temp\ecj1746300895
[ecj-lint] Compiling 127 source files to C:\Users\jenkins\AppData\Local\Temp\ecj1746300895
[ecj-lint] ----------
[ecj-lint] 1. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 21)
[ecj-lint] import java.util.Collection;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import java.util.Collection is never used
[ecj-lint] ----------
[ecj-lint] 2. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 22)
[ecj-lint] import java.util.HashSet;
[ecj-lint] ^^^^^^^^^^^^^^^^^
[ecj-lint] The import java.util.HashSet is never used
[ecj-lint] ----------
[ecj-lint] 3. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 24)
[ecj-lint] import java.util.List;
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] The import java.util.List is never used
[ecj-lint] ----------
[ecj-lint] 4. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 25)
[ecj-lint] import java.util.Set;
[ecj-lint] ^^^^^^^^^^^^^
[ecj-lint] The import java.util.Set is never used
[ecj-lint] ----------
[ecj-lint] 5. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 27)
[ecj-lint] import org.apache.lucene.analysis.Analyzer;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.analysis.Analyzer is never used
[ecj-lint] ----------
[ecj-lint] 6. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 33)
[ecj-lint] import org.apache.lucene.index.IndexableField;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.index.IndexableField is never used
[ecj-lint] ----------
[ecj-lint] 6 problems (6 errors)
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:765: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:101: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build.xml:204: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2177: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:1992: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2031: Compile failed; see the compiler error output for details.
Total time: 96 minutes 35 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any