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 2017/05/23 10:33:33 UTC
[JENKINS] Lucene-Solr-6.x-Windows (64bit/jdk1.8.0_131) - Build #
907 - Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/907/
Java: 64bit/jdk1.8.0_131 -XX:+UseCompressedOops -XX:+UseSerialGC
All tests passed
Build Log:
[...truncated 45949 lines...]
-ecj-javadoc-lint-src:
[mkdir] Created dir: C:\Users\jenkins\AppData\Local\Temp\ecj123013799
[ecj-lint] Compiling 24 source files to C:\Users\jenkins\AppData\Local\Temp\ecj123013799
[ecj-lint] ----------
[ecj-lint] 1. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\join\src\java\org\apache\lucene\search\join\TermsIncludingScoreQuery.java (at line 39)
[ecj-lint] import org.apache.lucene.util.LegacyNumericUtils;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.util.LegacyNumericUtils is never used
[ecj-lint] ----------
[ecj-lint] 1 problem (1 error)
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:775: 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:208: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2179: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2004: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2043: Compile failed; see the compiler error output for details.
Total time: 69 minutes 33 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 (64bit/jdk1.8.0_131) - Build #
908 - Still unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/908/
Java: 64bit/jdk1.8.0_131 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults
Error Message:
mismatch: 'myid1'!='myid' @ response/docs/[0]/id
Stack Trace:
java.lang.RuntimeException: mismatch: 'myid1'!='myid' @ response/docs/[0]/id
at __randomizedtesting.SeedInfo.seed([A420333032C31835:960A34AECA3D3CEC]:0)
at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:983)
at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:930)
at org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13190 lines...]
[junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\init-core-data-001
[junit4] 2> 2443788 WARN (SUITE-TestUseDocValuesAsStored-seed#[A420333032C31835]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 2443788 INFO (SUITE-TestUseDocValuesAsStored-seed#[A420333032C31835]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 2443796 INFO (SUITE-TestUseDocValuesAsStored-seed#[A420333032C31835]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2443798 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse
[junit4] 2> 2443808 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 2443859 WARN (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2443860 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
[junit4] 2> 2443884 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema-non-stored-docvalues.xml instead
[junit4] 2> 2443886 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
[junit4] 2> 2443893 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 2443894 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-001\collection1\conf\managed-schema
[junit4] 2> 2443897 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-001\collection1\conf\schema-non-stored-docvalues.xml to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-001\collection1\conf\schema-non-stored-docvalues.xml.bak
[junit4] 2> 2443903 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 2444002 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2444004 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2444004 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2444072 WARN (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2444073 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
[junit4] 2> 2444099 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
[junit4] 2> 2444113 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 2444113 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-001\collection1, trusted=true
[junit4] 2> 2444113 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2444113 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2444113 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\init-core-data-001\]
[junit4] 2> 2444121 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2837398218376158]
[junit4] 2> 2444128 WARN (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2444291 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2444291 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2444292 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.45480341969090776]
[junit4] 2> 2444292 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2babeff0[collection1] main]
[junit4] 2> 2444293 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-001\collection1\conf
[junit4] 2> 2444293 INFO (coreLoadExecutor-10147-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2444293 INFO (searcherExecutor-10148-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2babeff0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2444295 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 2444297 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid]} 0 1
[junit4] 2> 2444306 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2444306 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@46ab783e
[junit4] 2> 2444339 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@cf889a7[collection1] main]
[junit4] 2> 2444340 INFO (searcherExecutor-10148-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@cf889a7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)))}
[junit4] 2> 2444340 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2444340 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 34
[junit4] 2> 2444341 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:myid&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2444341 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:myid&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2444341 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:myid&fl=id,nonstored_dv_*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2444341 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:myid&fl=id,nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2444342 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2444342 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
[junit4] 2> 2444342 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2444342 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1372283983
[junit4] 2> 2444342 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 2444343 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 2444343 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 2444343 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2444345 INFO (coreCloseExecutor-10152-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4f8491ff
[junit4] 2> 2444346 INFO (coreCloseExecutor-10152-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 2444350 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse
[junit4] 2> 2444362 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults
[junit4] 2> 2444388 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 2444416 WARN (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2444417 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
[junit4] 2> 2444430 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema-non-stored-docvalues.xml instead
[junit4] 2> 2444433 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
[junit4] 2> 2444451 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 2444452 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-002\collection1\conf\managed-schema
[junit4] 2> 2444454 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-002\collection1\conf\schema-non-stored-docvalues.xml to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-002\collection1\conf\schema-non-stored-docvalues.xml.bak
[junit4] 2> 2444459 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 2444568 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2444571 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2444571 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2444683 WARN (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2444683 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
[junit4] 2> 2444716 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
[junit4] 2> 2444731 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 2444732 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-002\collection1, trusted=true
[junit4] 2> 2444732 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2444732 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2444732 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\init-core-data-001\]
[junit4] 2> 2444740 WARN (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2444885 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2444886 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2444888 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2837398218376158]
[junit4] 2> 2444908 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@735d2bee[collection1] main]
[junit4] 2> 2444909 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-002\collection1\conf
[junit4] 2> 2444909 INFO (coreLoadExecutor-10159-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2444909 INFO (searcherExecutor-10160-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@735d2bee[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1)))}
[junit4] 2> 2444911 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 2444913 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid1]} 0 1
[junit4] 2> 2444913 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid2]} 0 0
[junit4] 2> 2444913 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid3]} 0 0
[junit4] 2> 2444914 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid4]} 0 0
[junit4] 2> 2444914 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid5]} 0 0
[junit4] 2> 2444914 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid6]} 0 0
[junit4] 2> 2444915 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2444915 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@20a0493b
[junit4] 2> 2444964 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@254a1df4[collection1] main]
[junit4] 2> 2444965 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2444966 INFO (searcherExecutor-10160-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@254a1df4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1) Uninverting(_1(6.7.0):c6)))}
[junit4] 2> 2444966 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 51
[junit4] 2> 2444967 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:myid*&fl=*&wt=xml} hits=7 status=0 QTime=0
[junit4] 2> 2444968 ERROR (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 query failed JSON validation. error=mismatch: 'myid1'!='myid' @ response/docs/[0]/id
[junit4] 2> expected =/response/docs==[{'id':'myid1','test_is_dvo':[101,102,103]},{'id':'myid2','test_is_dvo':[201,202]},{'id':'myid3','test_is_dvo':[301,302,303,304]},{'id':'myid4','test_s_dvo':'hello','test_is_dvo':[401,402]},{'id':'myid5'},{'id':'myid6','test_s_dvo':'hello'}]
[junit4] 2> response = {
[junit4] 2> "responseHeader":{
[junit4] 2> "status":0,
[junit4] 2> "QTime":0},
[junit4] 2> "response":{"numFound":7,"start":0,"docs":[
[junit4] 2> {
[junit4] 2> "id":"myid"},
[junit4] 2> {
[junit4] 2> "id":"myid1",
[junit4] 2> "test_is_dvo":[101,
[junit4] 2> 102,
[junit4] 2> 103]},
[junit4] 2> {
[junit4] 2> "id":"myid2",
[junit4] 2> "test_is_dvo":[201,
[junit4] 2> 202]},
[junit4] 2> {
[junit4] 2> "id":"myid3",
[junit4] 2> "test_is_dvo":[301,
[junit4] 2> 302,
[junit4] 2> 303,
[junit4] 2> 304]},
[junit4] 2> {
[junit4] 2> "id":"myid4",
[junit4] 2> "test_s_dvo":"hello",
[junit4] 2> "test_is_dvo":[401,
[junit4] 2> 402]},
[junit4] 2> {
[junit4] 2> "id":"myid5"},
[junit4] 2> {
[junit4] 2> "id":"myid6",
[junit4] 2> "test_s_dvo":"hello"}]
[junit4] 2> }}
[junit4] 2>
[junit4] 2> request = q=id:myid*&fl=*&wt=xml
[junit4] 2> 2444969 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2444969 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
[junit4] 2> 2444969 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2444969 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=370786954
[junit4] 2> 2444969 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 2444970 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 2444971 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 2444971 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2444972 INFO (coreCloseExecutor-10164-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4a4c4712
[junit4] 2> 2444972 INFO (coreCloseExecutor-10164-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 2445010 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults -Dtests.seed=A420333032C31835 -Dtests.slow=true -Dtests.locale=en-NZ -Dtests.timezone=Europe/Simferopol -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.66s J0 | TestUseDocValuesAsStored.testMultipleSearchResults <<<
[junit4] > Throwable #1: java.lang.RuntimeException: mismatch: 'myid1'!='myid' @ response/docs/[0]/id
[junit4] > at __randomizedtesting.SeedInfo.seed([A420333032C31835:960A34AECA3D3CEC]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:983)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:930)
[junit4] > at org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 2445020 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued
[junit4] 2> 2445032 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 2445091 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2445092 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
[junit4] 2> 2445184 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema-non-stored-docvalues.xml instead
[junit4] 2> 2445187 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
[junit4] 2> 2445196 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 2445197 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-003\collection1\conf\managed-schema
[junit4] 2> 2445200 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-003\collection1\conf\schema-non-stored-docvalues.xml to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-003\collection1\conf\schema-non-stored-docvalues.xml.bak
[junit4] 2> 2445206 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 2445332 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2445335 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2445335 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2445375 WARN (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2445375 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
[junit4] 2> 2445383 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
[junit4] 2> 2445411 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 2445411 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-003\collection1, trusted=true
[junit4] 2> 2445411 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@98daa4f
[junit4] 2> 2445411 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2445411 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-003\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\init-core-data-001\]
[junit4] 2> 2445417 WARN (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 2445523 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2445523 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2445524 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2837398218376158]
[junit4] 2> 2445567 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@728f931b[collection1] main]
[junit4] 2> 2445568 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.schema.TestUseDocValuesAsStored_A420333032C31835-001\tempDir-003\collection1\conf
[junit4] 2> 2445568 INFO (coreLoadExecutor-10171-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2445568 INFO (searcherExecutor-10172-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@728f931b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):c1) Uninverting(_1(6.7.0):c6)))}
[junit4] 2> 2445569 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 2445569 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2445570 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
[junit4] 2> 2445577 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 6
[junit4] 2> 2445577 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2445577 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2445611 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f745b30[collection1] main]
[junit4] 2> 2445612 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2f745b30[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1)))}
[junit4] 2> 2445612 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2445614 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 37
[junit4] 2> 2445615 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445621 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445622 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445624 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445626 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445634 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445636 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 2445645 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2445645 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2445688 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3b9261c6[collection1] main]
[junit4] 2> 2445688 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2445689 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3b9261c6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1)))}
[junit4] 2> 2445689 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 44
[junit4] 2> 2445689 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445690 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445691 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445692 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445692 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445694 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 2445699 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 2445700 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2445700 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2445738 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@778508f5[collection1] main]
[junit4] 2> 2445739 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2445739 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@778508f5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1)))}
[junit4] 2> 2445739 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 39
[junit4] 2> 2445740 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445741 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445742 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445743 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445743 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445743 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 2445745 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 1
[junit4] 2> 2445745 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2445745 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2445757 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@477c6b23[collection1] main]
[junit4] 2> 2445758 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2445759 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@477c6b23[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1)))}
[junit4] 2> 2445760 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 13
[junit4] 2> 2445760 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445761 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445767 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=*dvo&wt=xml} hits=1 status=0 QTime=5
[junit4] 2> 2445769 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445770 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445771 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
[junit4] 2> 2445773 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 2445773 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2445773 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2445811 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d38267c[collection1] main]
[junit4] 2> 2445811 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2445811 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d38267c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) Uninverting(_6(6.7.0):c1)))}
[junit4] 2> 2445811 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 38
[junit4] 2> 2445811 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_f_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445812 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_f_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445812 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445812 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445814 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_f_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445814 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
[junit4] 2> 2445815 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 2445815 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2445815 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2445876 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7c0f902c[collection1] main]
[junit4] 2> 2445877 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2445877 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7c0f902c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) Uninverting(_6(6.7.0):c1) Uninverting(_7(6.7.0):c1)))}
[junit4] 2> 2445877 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 62
[junit4] 2> 2445879 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445894 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445895 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445897 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445898 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445899 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 2445902 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 2445902 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2445902 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2445934 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6c4d83ed[collection1] main]
[junit4] 2> 2445934 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2445935 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6c4d83ed[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) Uninverting(_6(6.7.0):c1) Uninverting(_7(6.7.0):c1) Uninverting(_8(6.7.0):c1)))}
[junit4] 2> 2445935 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 32
[junit4] 2> 2445935 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445937 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445939 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=*_dv&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445940 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445941 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_ss_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445942 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
[junit4] 2> 2445944 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 2445945 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2445945 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2445981 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7502a934[collection1] main]
[junit4] 2> 2445982 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2445982 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7502a934[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) Uninverting(_6(6.7.0):c1) Uninverting(_7(6.7.0):c1) Uninverting(_8(6.7.0):c1) Uninverting(_9(6.7.0):c1)))}
[junit4] 2> 2445982 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 37
[junit4] 2> 2445984 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_ss_dvo2&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445985 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_ss_dvo2,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445986 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=*vo2&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445987 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445989 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_ss_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2445990 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
[junit4] 2> 2445992 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 2445994 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2445994 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2446022 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@8944a8d[collection1] main]
[junit4] 2> 2446023 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2446023 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8944a8d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.7.0):c1) Uninverting(_3(6.7.0):c1) Uninverting(_4(6.7.0):c1) Uninverting(_5(6.7.0):c1) Uninverting(_6(6.7.0):c1) Uninverting(_7(6.7.0):c1) Uninverting(_8(6.7.0):c1) Uninverting(_9(6.7.0):c1) Uninverting(_a(6.7.0):c1)))}
[junit4] 2> 2446024 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 30
[junit4] 2> 2446025 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&fl=enums_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446027 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&fl=enums_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446027 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446028 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446029 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&fl=enums_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446030 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0
[junit4] 2> 2446031 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2446031 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
[junit4] 2> 2446032 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10]} 0 0
[junit4] 2> 2446032 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2446032 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2446159 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3044c2e0[collection1] main]
[junit4] 2> 2446159 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3044c2e0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1)))}
[junit4] 2> 2446159 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2446171 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 139
[junit4] 2> 2446171 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&fl=test_s_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446172 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&fl=test_s_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446172 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446173 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446173 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&fl=test_s_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446173 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446174 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11]} 0 0
[junit4] 2> 2446175 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2446175 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2446251 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1dbfa700[collection1] main]
[junit4] 2> 2446262 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1dbfa700[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1) Uninverting(_c(6.7.0):c1)))}
[junit4] 2> 2446267 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2446279 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 104
[junit4] 2> 2446281 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446282 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446282 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446285 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446286 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:11&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446286 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 2446287 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[12]} 0 0
[junit4] 2> 2446287 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2446287 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2446296 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@24bd9196[collection1] main]
[junit4] 2> 2446297 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2446297 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@24bd9196[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1) Uninverting(_c(6.7.0):c1) Uninverting(_d(6.7.0):c1)))}
[junit4] 2> 2446299 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 10
[junit4] 2> 2446299 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&fl=test_ds_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446301 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&fl=test_ds_dvo,*&wt=xml} hits=1 status=0 QTime=1
[junit4] 2> 2446302 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446304 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446305 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&fl=test_ds_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446307 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 2446319 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[13]} 0 1
[junit4] 2> 2446319 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2446319 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2446352 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@362a51e9[collection1] main]
[junit4] 2> 2446354 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2446355 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@362a51e9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1) Uninverting(_c(6.7.0):c1) Uninverting(_d(6.7.0):c1) Uninverting(_e(6.7.0):c1)))}
[junit4] 2> 2446355 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 35
[junit4] 2> 2446355 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&fl=test_l_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446355 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&fl=test_l_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446356 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446356 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446356 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&fl=test_l_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446361 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=3
[junit4] 2> 2446362 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[14]} 0 0
[junit4] 2> 2446362 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2446362 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2446421 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@43e80c8b[collection1] main]
[junit4] 2> 2446422 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2446422 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@43e80c8b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1) Uninverting(_c(6.7.0):c1) Uninverting(_d(6.7.0):c1) Uninverting(_e(6.7.0):c1) Uninverting(_f(6.7.0):c1)))}
[junit4] 2> 2446422 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 60
[junit4] 2> 2446422 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446424 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446425 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446426 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446427 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446432 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
[junit4] 2> 2446435 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15]} 0 0
[junit4] 2> 2446435 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2446435 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2446462 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@32cd4b65[collection1] main]
[junit4] 2> 2446464 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2446464 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@32cd4b65[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1) Uninverting(_c(6.7.0):c1) Uninverting(_d(6.7.0):c1) Uninverting(_e(6.7.0):c1) Uninverting(_f(6.7.0):c1) Uninverting(_g(6.7.0):c1)))}
[junit4] 2> 2446464 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 28
[junit4] 2> 2446465 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&fl=test_dts_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446465 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&fl=test_dts_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446466 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446466 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446466 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&fl=test_dts_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 2446466 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 2446470 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[16]} 0 1
[junit4] 2> 2446470 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2446470 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@17be09cf
[junit4] 2> 2446512 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b63e659[collection1] main]
[junit4] 2> 2446512 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2446514 INFO (searcherExecutor-10172-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5b63e659[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.7.0):c1) Uninverting(_c(6.7.0):c1) Uninverting(_d(6.7.0):c1) Uninve
[...truncated too long message...]
tyIndex-seed#[A420333032C31835]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2450287 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A420333032C31835]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
[junit4] 2> 2450287 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2450287 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A420333032C31835]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=794216486
[junit4] 2> 2450287 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A420333032C31835]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 2450288 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A420333032C31835]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
[junit4] 2> 2450289 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A420333032C31835]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
[junit4] 2> 2450289 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A420333032C31835]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2450290 INFO (coreCloseExecutor-10212-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3aa56eed
[junit4] 2> 2450313 INFO (coreCloseExecutor-10212-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
[junit4] 2> 2450316 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[A420333032C31835]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testOnEmptyIndex
[junit4] 2> 2450317 INFO (SUITE-TestUseDocValuesAsStored-seed#[A420333032C31835]-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.schema.TestUseDocValuesAsStored_A420333032C31835-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=685, maxMBSortInHeap=5.1968071969907275, sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=en-NZ, timezone=Europe/Simferopol
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=3,threads=1,free=169958096,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [ClusterStateUpdateTest, DisMaxRequestHandlerTest, TestConfigSetImmutable, CloudExitableDirectoryReaderTest, QueryElevationComponentTest, TestXIncludeConfig, MoveReplicaTest, TestCryptoKeys, TestLazyCores, PeerSyncTest, DirectSolrConnectionTest, CustomCollectionTest, TestBackupRepositoryFactory, ActionThrottleTest, TestLeaderElectionWithEmptyReplica, TestPayloadScoreQParserPlugin, TestSolrDynamicMBean, TestSchemalessBufferedUpdates, SolrPluginUtilsTest, TestPartialUpdateDeduplication, TestSolrIndexConfig, TestHalfAndHalfDocValues, HLLSerializationTest, OverriddenZkACLAndCredentialsProvidersTest, AddBlockUpdateTest, DistributedFacetExistsSmallTest, ConcurrentDeleteAndCreateCollectionTest, TestBinaryResponseWriter, RankQueryTest, PrimitiveFieldTypeTest, TestExclusionRuleCollectionAccess, BlockDirectoryTest, LeaderElectionTest, TestDynamicFieldCollectionResource, TestConfigsApi, DateFieldTest, IndexSchemaRuntimeFieldTest, OpenExchangeRatesOrgProviderTest, TestOverriddenPrefixQueryForCustomFieldType, TestSort, ConfigureRecoveryStrategyTest, TestExtendedDismaxParser, TestCloudPivotFacet, FastVectorHighlighterTest, PropertiesRequestHandlerTest, ManagedSchemaRoundRobinCloudTest, TestSchemaNameResource, PingRequestHandlerTest, DocValuesNotIndexedTest, OverseerTest, TestConfigSetsAPIExclusivity, ConnectionManagerTest, JSONWriterTest, BlockJoinFacetSimpleTest, OverseerStatusTest, TestConfigSets, TestReplicationHandler, TestUseDocValuesAsStored2, TestStressVersions, DocValuesMultiTest, TestLeaderInitiatedRecoveryThread, TestPointFields, DirectSolrSpellCheckerTest, OverseerModifyCollectionTest, TestSolrJ, BigEndianAscendingWordSerializerTest, TestFieldCacheVsDocValues, TestGroupingSearch, AssignTest, TestImpersonationWithHadoopAuth, TestHighlightDedupGrouping, PathHierarchyTokenizerFactoryTest, IndexBasedSpellCheckerTest, SpatialFilterTest, RecoveryZkTest, RemoteQueryErrorTest, TestDFISimilarityFactory, TestJsonFacetRefinement, DistributedSpellCheckComponentTest, BaseCdcrDistributedZkTest, XmlUpdateRequestHandlerTest, TestSha256AuthenticationProvider, ReplicationFactorTest, TestMinMaxOnMultiValuedField, SimpleCollectionCreateDeleteTest, TestSubQueryTransformerCrossCore, TestRandomDVFaceting, InfixSuggestersTest, TestSchemaSimilarityResource, DistributedFacetPivotSmallTest, TestSurroundQueryParser, TestFunctionQuery, HdfsDirectoryTest, TestReloadDeadlock, TestPseudoReturnFields, BasicDistributedZkTest, TestAddFieldRealTimeGet, TestUpdate, TestClassNameShortening, TestRTGBase, TestRestoreCore, TestAnalyzeInfixSuggestions, SolrTestCaseJ4Test, CollectionsAPIAsyncDistributedZkTest, TestHashPartitioner, TestJettySolrRunner, TestSolrQueryParserResource, DocValuesMissingTest, TestTestInjection, TestMaxScoreQueryParser, RollingRestartTest, TestFieldCollectionResource, TestPivotHelperCode, TestDistributedSearch, ShardRoutingTest, DistributedMLTComponentTest, TestApiFramework, TestTolerantSearch, DistributedQueueTest, SpatialRPTFieldTypeTest, DistributedDebugComponentTest, V2ApiIntegrationTest, TestRequestStatusCollectionAPI, PrimUtilsTest, TestSegmentSorting, TestStressLucene, TestSimpleTextCodec, StressHdfsTest, PeerSyncWithIndexFingerprintCachingTest, TestElisionMultitermQuery, TestNumericTerms64, TestSerializedLuceneMatchVersion, TestExportWriter, TestFieldCacheSort, TestRawTransformer, TestRTimerTree, TestDocTermOrds, HttpPartitionTest, BlockJoinFacetDistribTest, MigrateRouteKeyTest, ClassificationUpdateProcessorTest, ConjunctionSolrSpellCheckerTest, TestFaceting, TestWordDelimiterFilterFactory, CdcrRequestHandlerTest, TestBinaryField, TestLegacyFieldCache, CdcrUpdateLogTest, DirectoryFactoryTest, CurrencyFieldXmlFileTest, SynonymTokenizerTest, StandardRequestHandlerTest, TestAnalyzedSuggestions, DOMUtilTest, ZkStateReaderTest, HighlighterTest, TestPKIAuthenticationPlugin, DistributedQueryComponentCustomSortTest, TestSizeLimitedDistributedMap, TestFieldCacheReopen, DocExpirationUpdateProcessorFactoryTest, TestLRUCache, TestManagedSchemaAPI, TestDocBasedVersionConstraints, ShufflingReplicaListTransformerTest, TestHdfsUpdateLog, SuggestComponentContextFilterQueryTest, SolrRequestParserTest, SimplePostToolTest, TestReplicationHandlerBackup, TestCloudManagedSchema, TestRandomRequestDistribution, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SolrCoreTest, BadIndexSchemaTest, TestFiltering, TestBadConfig, SoftAutoCommitTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, TestFoldingMultitermQuery, DocValuesTest, SolrCoreCheckLockOnStartupTest, QueryEqualityTest, TestRemoteStreaming, SolrInfoMBeanTest, LukeRequestHandlerTest, TermsComponentTest, TestValueSourceCache, MoreLikeThisHandlerTest, LoggingHandlerTest, TestCollationField, JSONWriterTest, TestCSVResponseWriter, UniqFieldsUpdateProcessorFactoryTest, CSVRequestHandlerTest, TestLFUCache, TestSolrDeletionPolicy2, SampleTest, TestConfig, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestPhraseSuggestions, TestCodecSupport, TestPerFieldSimilarity, TestFastWriter, TimeZoneUtilsTest, TestFastOutputStream, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, CdcrReplicationDistributedZkTest, CleanupOldIndexTest, CollectionStateFormat2Test, CollectionsAPISolrJTest, DeleteStatusTest, DistributedVersionInfoTest, LeaderFailoverAfterPartitionTest, LeaderFailureAfterFreshStartTest, LeaderInitiatedRecoveryOnCommitTest, NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerRolesTest, OverseerTaskQueueTest, PeerSyncReplicationTest, RecoveryAfterSoftCommitTest, RestartWhileUpdatingTest, SSLMigrationTest, SaslZkACLProviderTest, SharedFSAutoReplicaFailoverTest, SolrCLIZkUtilsTest, TestAuthenticationFramework, TestCloudInspectUtil, TestCloudRecovery, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestLockTree, TestMiniSolrCloudCluster, TestReplicaProperties, TestSSLRandomization, TestSolrCloudWithDelegationTokens, TestSolrCloudWithSecureImpersonation, TestStressCloudBlindAtomicUpdates, TestStressLiveNodes, TestTolerantUpdateProcessorRandomCloud, VMParamsZkACLAndCredentialsProvidersTest, HdfsBasicDistributedZk2Test, HdfsChaosMonkeyNothingIsSafeTest, HdfsChaosMonkeySafeLeaderTest, HdfsCollectionsAPIDistributedZkTest, HdfsNNFailoverTest, HdfsRecoverLeaseTest, HdfsRecoveryZkTest, HdfsRestartWhileUpdatingTest, HdfsThreadLeakTest, HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, ZkStateWriterTest, RulesTest, TestDynamicLoading, RequestLoggingTest, TestConfigReload, TestSolrConfigHandlerConcurrent, TestSystemCollAutoCreate, CoreAdminRequestStatusTest, CoreMergeIndexesAdminHandlerTest, InfoHandlerTest, SecurityConfHandlerTest, SegmentsInfoRequestHandlerTest, DistributedFacetPivotLongTailTest, DistributedSuggestComponentTest, TestDistributedStatsComponentCardinality, TestGeoJSONResponseWriter, TestChildDocTransformer, TestManagedResource, TestRestManager, TestBulkSchemaAPI, TestUniqueKeyFieldResource, BadCopyFieldTest, BooleanFieldTest, ChangedSchemaMergeTest, DateRangeFieldTest, EnumFieldTest, TestBulkSchemaConcurrent, TestManagedSchema, TestSchemaManager, TestUseDocValuesAsStored]
[junit4] Completed [635/712 (1!)] on J0 in 6.59s, 6 tests, 1 error <<< FAILURES!
[...truncated 43712 lines...]
Re: [JENKINS] Lucene-Solr-6.x-Windows (64bit/jdk1.8.0_131) - Build #
907 - Failure!
Posted by Martijn van Groningen <mv...@apache.org>.
I pushed a fix for this failure.
On 23 May 2017 at 12:33, Policeman Jenkins Server <je...@thetaphi.de>
wrote:
> Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/907/
> Java: 64bit/jdk1.8.0_131 -XX:+UseCompressedOops -XX:+UseSerialGC
>
> All tests passed
>
> Build Log:
> [...truncated 45949 lines...]
> -ecj-javadoc-lint-src:
> [mkdir] Created dir: C:\Users\jenkins\AppData\Local\Temp\ecj123013799
> [ecj-lint] Compiling 24 source files to C:\Users\jenkins\AppData\
> Local\Temp\ecj123013799
> [ecj-lint] ----------
> [ecj-lint] 1. ERROR in C:\Users\jenkins\workspace\
> Lucene-Solr-6.x-Windows\lucene\join\src\java\org\
> apache\lucene\search\join\TermsIncludingScoreQuery.java (at line 39)
> [ecj-lint] import org.apache.lucene.util.LegacyNumericUtils;
> [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
> [ecj-lint] The import org.apache.lucene.util.LegacyNumericUtils is never
> used
> [ecj-lint] ----------
> [ecj-lint] 1 problem (1 error)
>
> BUILD FAILED
> C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:775: 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:208:
> The following error occurred while executing this line:
> C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2179:
> The following error occurred while executing this line:
> C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2004:
> The following error occurred while executing this line:
> C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2043:
> Compile failed; see the compiler error output for details.
>
> Total time: 69 minutes 33 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
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org
> For additional commands, e-mail: dev-help@lucene.apache.org
>