Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/3235/ Java: 32bit/jdk1.8.0_121 -client -XX:+UseParallelGC
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([34A412C10041D8D9:68E155FF8BFFC00]:0) at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:969) at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:916) 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:745) Build Log: [...truncated 11306 lines...] [junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/init-core-data-001 [junit4] 2> 158402 WARN (SUITE-TestUseDocValuesAsStored-seed#[34A412C10041D8D9]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=767 numCloses=767 [junit4] 2> 158403 INFO (SUITE-TestUseDocValuesAsStored-seed#[34A412C10041D8D9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields [junit4] 2> 158404 INFO (SUITE-TestUseDocValuesAsStored-seed#[34A412C10041D8D9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 158409 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testUseDocValuesAsStoredFalse [junit4] 2> 158411 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 158432 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0 [junit4] 2> 158446 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] 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> 158477 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues [junit4] 2> 158491 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id [junit4] 2> 158518 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1/conf/managed-schema [junit4] 2> 158585 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak [junit4] 2> 158590 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true [junit4] 2> 158623 WARN (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 158628 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 158628 WARN (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 158634 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 158634 WARN (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 158634 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 158651 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0 [junit4] 2> 158669 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues [junit4] 2> 158685 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id [junit4] 2> 158685 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1, trusted=true [junit4] 2> 158686 WARN (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 158686 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 158686 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 158686 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/init-core-data-001/] [junit4] 2> 158689 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=57.7744140625, floorSegmentMB=2.1494140625, forceMergeDeletesPctAllowed=18.124101851191075, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 158807 WARN (coreLoadExecutor-782-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> 158855 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 158855 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 158857 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=22, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 158858 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17ab395[collection1] main] [junit4] 2> 158858 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-001/collection1/conf [junit4] 2> 158858 INFO (coreLoadExecutor-782-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 158859 INFO (searcherExecutor-783-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17ab395[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 158860 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 158861 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid]} 0 1 [junit4] 2> 158865 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 158865 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1bcef23 [junit4] 2> 159239 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c2edfd[collection1] main] [junit4] 2> 159240 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 159240 INFO (searcherExecutor-783-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c2edfd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)))} [junit4] 2> 159240 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 375 [junit4] 2> 159242 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] 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> 159243 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] 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> 159243 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] 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> 159243 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] 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> 159245 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 159245 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=14108827 [junit4] 2> 159245 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 159245 INFO (coreCloseExecutor-787-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@acb937 [junit4] 2> 159255 INFO (coreCloseExecutor-787-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 159262 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 159263 INFO (TEST-TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testUseDocValuesAsStoredFalse [junit4] 2> 159266 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleSearchResults [junit4] 2> 159268 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 159284 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0 [junit4] 2> 159296 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] 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> 159302 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues [junit4] 2> 159314 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id [junit4] 2> 159314 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1/conf/managed-schema [junit4] 2> 159376 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak [junit4] 2> 159378 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true [junit4] 2> 159406 WARN (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 159410 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 159410 WARN (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 159416 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 159416 WARN (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 159416 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 159438 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0 [junit4] 2> 159457 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues [junit4] 2> 159473 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id [junit4] 2> 159473 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1, trusted=true [junit4] 2> 159474 WARN (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 159474 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 159474 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 159475 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/init-core-data-001/] [junit4] 2> 159479 WARN (coreLoadExecutor-794-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> 159531 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 159531 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 159533 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=57.7744140625, floorSegmentMB=2.1494140625, forceMergeDeletesPctAllowed=18.124101851191075, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 159536 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f884c[collection1] main] [junit4] 2> 159537 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-002/collection1/conf [junit4] 2> 159537 INFO (coreLoadExecutor-794-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 159537 INFO (searcherExecutor-795-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1f884c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1)))} [junit4] 2> 159539 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 159560 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid1]} 0 2 [junit4] 2> 159562 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid2]} 0 0 [junit4] 2> 159564 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid3]} 0 0 [junit4] 2> 159565 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid4]} 0 0 [junit4] 2> 159566 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid5]} 0 0 [junit4] 2> 159567 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[myid6]} 0 0 [junit4] 2> 159568 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 159568 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5f0625 [junit4] 2> 160089 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ab36dd[collection1] main] [junit4] 2> 160090 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 160090 INFO (searcherExecutor-795-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ab36dd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1) Uninverting(_1(6.6.0):C6)))} [junit4] 2> 160090 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 523 [junit4] 2> 160099 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:myid*&fl=*&wt=xml} hits=7 status=0 QTime=8 [junit4] 2> 160101 ERROR (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] 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":8}, [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> 160101 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 160101 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=27103897 [junit4] 2> 160101 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 160102 INFO (coreCloseExecutor-799-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@18f1391 [junit4] 2> 160106 INFO (coreCloseExecutor-799-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 160109 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 160110 INFO (TEST-TestUseDocValuesAsStored.testMultipleSearchResults-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleSearchResults [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestUseDocValuesAsStored -Dtests.method=testMultipleSearchResults -Dtests.seed=34A412C10041D8D9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-SY -Dtests.timezone=Europe/Copenhagen -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.87s J0 | TestUseDocValuesAsStored.testMultipleSearchResults <<< [junit4] > Throwable #1: java.lang.RuntimeException: mismatch: 'myid1'!='myid' @ response/docs/[0]/id [junit4] > at __randomizedtesting.SeedInfo.seed([34A412C10041D8D9:68E155FF8BFFC00]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:969) [junit4] > at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:916) [junit4] > at org.apache.solr.schema.TestUseDocValuesAsStored.testMultipleSearchResults(TestUseDocValuesAsStored.java:243) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 160136 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomSingleAndMultiValued [junit4] 2> 160139 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 160158 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0 [junit4] 2> 160177 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 160185 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues [junit4] 2> 160198 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id [junit4] 2> 160198 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1/conf/managed-schema [junit4] 2> 160263 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak [junit4] 2> 160265 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true [junit4] 2> 160291 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 160297 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 160297 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 160304 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 160304 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 160304 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 160325 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0 [junit4] 2> 160341 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues [junit4] 2> 160353 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id [junit4] 2> 160354 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1, trusted=true [junit4] 2> 160354 WARN (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 160354 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 160354 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 160354 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/init-core-data-001/] [junit4] 2> 160360 WARN (coreLoadExecutor-806-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> 160424 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 160425 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 160426 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=57.7744140625, floorSegmentMB=2.1494140625, forceMergeDeletesPctAllowed=18.124101851191075, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 160430 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12c445d[collection1] main] [junit4] 2> 160431 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-003/collection1/conf [junit4] 2> 160431 INFO (coreLoadExecutor-806-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 160431 INFO (searcherExecutor-807-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12c445d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C1) Uninverting(_1(6.6.0):C6)))} [junit4] 2> 160432 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 160441 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 160442 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 160443 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0 [junit4] 2> 160444 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 160444 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 160942 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@dfef29[collection1] main] [junit4] 2> 160943 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 160943 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@dfef29[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1)))} [junit4] 2> 160945 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 501 [junit4] 2> 160946 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&fl=test_s_dvo&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 160948 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&fl=test_s_dvo,*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 160949 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 160953 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 160954 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&fl=test_s_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 160955 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 160957 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0 [junit4] 2> 160958 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 160958 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 161442 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@174a3b5[collection1] main] [junit4] 2> 161443 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 161444 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@174a3b5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1) Uninverting(_3(6.6.0):C1)))} [junit4] 2> 161444 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 486 [junit4] 2> 161446 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_i_dvo&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 161447 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_i_dvo,*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 161449 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 161450 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 161452 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_i_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 161453 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0 [junit4] 2> 161456 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 1 [junit4] 2> 161457 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 161466 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 161869 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@510c02[collection1] main] [junit4] 2> 161870 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 161870 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@510c02[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1) Uninverting(_3(6.6.0):C1) Uninverting(_4(6.6.0):C1)))} [junit4] 2> 161870 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 412 [junit4] 2> 161871 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 161873 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 161874 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 161875 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 161877 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 161878 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0 [junit4] 2> 161880 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 1 [junit4] 2> 161880 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 161880 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 162114 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@18049fc[collection1] main] [junit4] 2> 162114 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 162114 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@18049fc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1) Uninverting(_3(6.6.0):C1) Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1)))} [junit4] 2> 162115 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 234 [junit4] 2> 162117 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_l_dvo&wt=xml} hits=1 status=0 QTime=1 [junit4] 2> 162118 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_l_dvo,*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 162119 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=*dvo&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 162120 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 162122 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_l_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 162123 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0 [junit4] 2> 162125 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0 [junit4] 2> 162126 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 162126 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 162367 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@fa00f3[collection1] main] [junit4] 2> 162369 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 162369 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@fa00f3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1) Uninverting(_3(6.6.0):C1) Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1) Uninverting(_6(6.6.0):C1)))} [junit4] 2> 162369 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 243 [junit4] 2> 162371 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 162373 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 162375 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 162377 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 162379 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 162380 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0 [junit4] 2> 162383 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 1 [junit4] 2> 162384 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 162384 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 163346 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@14765fe[collection1] main] [junit4] 2> 163347 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 163347 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14765fe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1) Uninverting(_3(6.6.0):C1) Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1) Uninverting(_6(6.6.0):C1) Uninverting(_7(6.6.0):C1)))} [junit4] 2> 163347 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 962 [junit4] 2> 163349 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=test_dt_dvo&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 163350 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=test_dt_dvo,*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 163352 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=*dvo&wt=xml} hits=1 status=0 QTime=1 [junit4] 2> 163353 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 163355 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=test_dt_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 163356 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0 [junit4] 2> 163359 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 1 [junit4] 2> 163359 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 163359 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 163865 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a09157[collection1] main] [junit4] 2> 163866 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 163866 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a09157[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1) Uninverting(_3(6.6.0):C1) Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1) Uninverting(_6(6.6.0):C1) Uninverting(_7(6.6.0):C1) Uninverting(_8(6.6.0):C1)))} [junit4] 2> 163866 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 506 [junit4] 2> 163867 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_s_dv&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 163869 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_s_dv,*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 163870 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 163871 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 163873 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_s_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 163873 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0 [junit4] 2> 163876 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 1 [junit4] 2> 163877 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 163877 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 164301 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@e8b16d[collection1] main] [junit4] 2> 164302 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 164302 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e8b16d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1) Uninverting(_3(6.6.0):C1) Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1) Uninverting(_6(6.6.0):C1) Uninverting(_7(6.6.0):C1) Uninverting(_8(6.6.0):C1) Uninverting(_9(6.6.0):C1)))} [junit4] 2> 164302 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 425 [junit4] 2> 164303 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 164306 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 164308 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 164310 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 164313 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 164315 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0 [junit4] 2> 164318 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 1 [junit4] 2> 164319 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 164319 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 164635 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2cbb9[collection1] main] [junit4] 2> 164636 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 164636 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2cbb9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.6.0):C1) Uninverting(_3(6.6.0):C1) Uninverting(_4(6.6.0):C1) Uninverting(_5(6.6.0):C1) Uninverting(_6(6.6.0):C1) Uninverting(_7(6.6.0):C1) Uninverting(_8(6.6.0):C1) Uninverting(_9(6.6.0):C1) Uninverting(_a(6.6.0):C1)))} [junit4] 2> 164636 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 317 [junit4] 2> 164638 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&fl=enum_dvo&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 164639 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&fl=enum_dvo,*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 164640 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 164641 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 164642 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:9&fl=enum_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 164643 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=9 status=0 QTime=0 [junit4] 2> 164645 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 164645 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 164646 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[10]} 0 0 [junit4] 2> 164647 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 164651 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 165009 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@264f5a[collection1] main] [junit4] 2> 165010 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 165010 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@264f5a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):C1)))} [junit4] 2> 165014 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 367 [junit4] 2> 165015 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&fl=test_ss_dvo&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 165017 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&fl=test_ss_dvo,*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 165018 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165020 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165022 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:10&fl=test_ss_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 165023 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 165026 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11]} 0 1 [junit4] 2> 165027 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 165027 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 165286 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bb7a06[collection1] main] [junit4] 2> 165287 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 165287 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1bb7a06[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):C1) Uninverting(_c(6.6.0):C1)))} [junit4] 2> 165287 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 260 [junit4] 2> 165288 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165290 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165292 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165294 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165295 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165297 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0 [junit4] 2> 165300 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[12]} 0 1 [junit4] 2> 165300 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 165300 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 165474 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@e5df21[collection1] main] [junit4] 2> 165475 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 165475 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e5df21[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):C1) Uninverting(_c(6.6.0):C1) Uninverting(_d(6.6.0):C1)))} [junit4] 2> 165476 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 175 [junit4] 2> 165477 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&fl=test_d_dvo&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 165479 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&fl=test_d_dvo,*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 165480 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165481 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165482 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:12&fl=test_d_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 165484 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0 [junit4] 2> 165486 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[13]} 0 1 [junit4] 2> 165487 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 165487 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 165710 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@13d5b20[collection1] main] [junit4] 2> 165711 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 165711 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@13d5b20[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):C1) Uninverting(_c(6.6.0):C1) Uninverting(_d(6.6.0):C1) Uninverting(_e(6.6.0):C1)))} [junit4] 2> 165711 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 224 [junit4] 2> 165713 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 165715 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 165717 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165719 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165721 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:13&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 165723 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0 [junit4] 2> 165726 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[14]} 0 1 [junit4] 2> 165727 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 165727 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 165954 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ba2032[collection1] main] [junit4] 2> 165955 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 165955 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ba2032[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):C1) Uninverting(_c(6.6.0):C1) Uninverting(_d(6.6.0):C1) Uninverting(_e(6.6.0):C1) Uninverting(_f(6.6.0):C1)))} [junit4] 2> 165955 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 228 [junit4] 2> 165956 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165959 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165960 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165962 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165964 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 165966 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0 [junit4] 2> 165971 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[15]} 0 1 [junit4] 2> 165972 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 165972 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 166210 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@159b106[collection1] main] [junit4] 2> 166211 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 166211 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@159b106[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):C1) Uninverting(_c(6.6.0):C1) Uninverting(_d(6.6.0):C1) Uninverting(_e(6.6.0):C1) Uninverting(_f(6.6.0):C1) Uninverting(_g(6.6.0):C1)))} [junit4] 2> 166211 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 239 [junit4] 2> 166213 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 166215 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 166216 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 166218 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 166221 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] 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> 166222 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0 [junit4] 2> 166226 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[16]} 0 0 [junit4] 2> 166226 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 166226 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1b09f96 [junit4] 2> 166432 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5167ee[collection1] main] [junit4] 2> 166433 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 166433 INFO (searcherExecutor-807-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5167ee[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(6.6.0):C1) Uninverting(_c(6.6.0):C1) Uninverting(_d(6.6.0):C1) Uninverting(_e(6.6.0):C1) Uninverting(_f(6.6.0):C1) Uninverting(_g(6.6.0):C1) Uninverting(_h(6.6.0):C1)))} [junit4] 2> 166433 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 207 [junit4] 2> 166434 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:16&fl=test_s_dv&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 166435 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:16&fl=test_s_dv,*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 166437 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:16&fl=*_dv&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 166437 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:16&fl=*&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 166438 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:16&fl=test_s_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 166439 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0 [junit4] 2> 166441 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[17]} 0 0 [junit4] 2> 166442 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes [...truncated too long message...] ma name=test-non-stored-docvalues [junit4] 2> 252539 INFO (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id [junit4] 2> 252539 INFO (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-006/collection1, trusted=true [junit4] 2> 252540 WARN (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 252540 INFO (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@17588c8 [junit4] 2> 252540 INFO (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 252540 INFO (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-006/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/init-core-data-001/] [junit4] 2> 252545 WARN (coreLoadExecutor-842-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> 252607 INFO (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 252607 INFO (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 252609 INFO (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=57.7744140625, floorSegmentMB=2.1494140625, forceMergeDeletesPctAllowed=18.124101851191075, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 252611 INFO (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@ef61c1[collection1] main] [junit4] 2> 252612 INFO (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001/tempDir-006/collection1/conf [junit4] 2> 252612 INFO (coreLoadExecutor-842-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 252612 INFO (searcherExecutor-843-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@ef61c1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7m(6.6.0):C1)))} [junit4] 2> 252614 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 252614 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX [junit4] 2> 252615 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] 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> 252616 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 1 [junit4] 2> 252617 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0 [junit4] 2> 252618 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0 [junit4] 2> 252619 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0 [junit4] 2> 252620 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 252620 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@c5b523 [junit4] 2> 252931 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@deb391[collection1] main] [junit4] 2> 252932 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 252932 INFO (searcherExecutor-843-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@deb391[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7n(6.6.0):C4)))} [junit4] 2> 252933 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 312 [junit4] 2> 252934 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:1&fl=test_mvt_dvt_st_str&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 252934 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_mvt_dvt_sf_str&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 252935 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_mvt_dvf_st_str&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 252935 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_mvt_dvu_st_str&wt=xml} hits=1 status=0 QTime=0 [junit4] 2> 252935 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 252936 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=16909966 [junit4] 2> 252936 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 252936 INFO (coreCloseExecutor-847-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3e1afb [junit4] 2> 252937 INFO (coreCloseExecutor-847-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 252941 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 252943 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[34A412C10041D8D9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMultivaluedOrdering [junit4] 2> 252946 INFO (SUITE-TestUseDocValuesAsStored-seed#[34A412C10041D8D9]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_34A412C10041D8D9-001 [junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=1021, maxDocsPerChunk=653, blockSize=506), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=1021, blockSize=506)), sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=ar-SY, timezone=Europe/Copenhagen [junit4] 2> NOTE: Linux 4.4.0-66-generic i386/Oracle Corporation 1.8.0_121 (32-bit)/cpus=12,threads=1,free=135219280,total=336330752 [junit4] 2> NOTE: All tests run in this JVM: [TestDynamicLoading, TestLockTree, TestRemoteStreaming, TestManagedResourceStorage, TestLRUStatsCache, TestAtomicUpdateErrorCases, TestInitQParser, RequiredFieldsTest, TestDocBasedVersionConstraints, TestSlowCompositeReaderWrapper, TestSearcherReuse, DocValuesNotIndexedTest, CdcrReplicationHandlerTest, ClassificationUpdateProcessorFactoryTest, TestDynamicFieldCollectionResource, HighlighterConfigTest, UUIDUpdateProcessorFallbackTest, BasicDistributedZkTest, TestUseDocValuesAsStored] [junit4] Completed [89/703 (1!)] on J0 in 94.59s, 6 tests, 1 error <<< FAILURES! [...truncated 41376 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org