You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/07/08 22:07:53 UTC
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk1.8.0) - Build # 231 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/231/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.schema.TestUseDocValuesAsStored.testDuplicateMultiValued
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([B941632FB6121D09:579C770F78A3EBB5]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:947)
at org.apache.solr.schema.TestUseDocValuesAsStored.doTest(TestUseDocValuesAsStored.java:367)
at org.apache.solr.schema.TestUseDocValuesAsStored.testDuplicateMultiValued(TestUseDocValuesAsStored.java:167)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//arr[@name='test_is_dvo']/int[.='42']
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="11" start="0"><doc><str name="id">myid1</str><arr name="test_is_dvo"><int>101</int><int>102</int><int>103</int></arr></doc><doc><str name="id">myid2</str><arr name="test_is_dvo"><int>201</int><int>202</int></arr></doc><doc><str name="id">myid3</str><arr name="test_is_dvo"><int>301</int><int>302</int><int>303</int><int>304</int></arr></doc><doc><str name="id">myid4</str><str name="test_s_dvo">hello</str><arr name="test_is_dvo"><int>401</int><int>402</int></arr></doc><doc><str name="id">myid5</str></doc><doc><str name="id">myid6</str><str name="test_s_dvo">hello</str></doc><doc><str name="id">myid</str></doc><doc><str name="id">1</str><arr name="test_ss_dvo"><str>X</str><str>Y</str></arr></doc><doc><str name="id">2</str><arr name="test_ss_dv"><str>X</str><str>X</str><str>Y</str></arr></doc><doc><str name="id">3</str><arr name="test_ss_dvo2"><str>X</str><str>Y</str></arr></doc></result>
</response>
request was:q=*:*&fl=*&wt=xml
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:940)
... 41 more
Build Log:
[...truncated 15676 lines...]
[junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/init-core-data-001
[junit4] 2> 3905510 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 3905520 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] 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> 3905522 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
[junit4] 2> 3905527 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 3905528 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-001/collection1/conf/managed-schema
[junit4] 2> 3905529 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml to /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-001/collection1/conf/schema-non-stored-docvalues.xml.bak
[junit4] 2> 3905875 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 3905876 WARN (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@57d7e5aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3905876 WARN (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@57d7e5aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3905880 WARN (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2faeea87[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3905880 WARN (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2faeea87[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3905927 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3905927 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3905951 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3905959 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3905959 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3905980 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 3905998 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
[junit4] 2> 3906007 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 3906007 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-001/collection1, trusted=true
[junit4] 2> 3906007 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3906007 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/init-core-data-001/]
[junit4] 2> 3906011 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=60.2041015625, floorSegmentMB=2.103515625, forceMergeDeletesPctAllowed=10.129434129661126, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7639175271484967, deletesPctAllowed=45.52058285529316
[junit4] 2> 3906014 WARN (coreLoadExecutor-20586-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> 3906085 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3906085 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3906085 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=73.27734375, floorSegmentMB=0.9814453125, forceMergeDeletesPctAllowed=22.583269537352518, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4231257339038301, deletesPctAllowed=40.01773315930333
[junit4] 2> 3906086 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4c9cb0a2[collection1] main]
[junit4] 2> 3906086 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-001/collection1/conf
[junit4] 2> 3906087 INFO (coreLoadExecutor-20586-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3906088 INFO (searcherExecutor-20587-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4c9cb0a2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3906094 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 3906094 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] 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> 3906094 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 3906099 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 3906100 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 3906100 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 3906100 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3906100 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4c29d4dd commitCommandVersion:0
[junit4] 2> 3906109 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e625b52[collection1] main]
[junit4] 2> 3906109 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3906110 INFO (searcherExecutor-20587-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2e625b52[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):c4:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620667394}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3906110 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 10
[junit4] 2> 3906111 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] 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> 3906112 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] 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> 3906112 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] 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> 3906113 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] 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> 3906113 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 3906113 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] 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> 3906113 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2090719643
[junit4] 2> 3906113 INFO (coreCloseExecutor-20591-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@56624780
[junit4] 2> 3906113 INFO (coreCloseExecutor-20591-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=56624780
[junit4] 2> 3906114 INFO (coreCloseExecutor-20591-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@22b94788: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@74175954
[junit4] 2> 3906122 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 3906122 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@575cd510: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@bcb7573
[junit4] 2> 3906126 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 3906127 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@21805c54: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@577fb63f
[junit4] 2> 3906129 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 3906129 INFO (TEST-TestUseDocValuesAsStored.testMultivaluedOrdering-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12a288b3: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@408121fa
[junit4] 2> 3906154 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 3906168 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] 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> 3906171 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
[junit4] 2> 3906177 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 3906178 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-002/collection1/conf/managed-schema
[junit4] 2> 3906179 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml to /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-002/collection1/conf/schema-non-stored-docvalues.xml.bak
[junit4] 2> 3906317 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 3906318 WARN (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@732a5bf2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3906318 WARN (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@732a5bf2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3906325 WARN (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@fe4caa4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3906325 WARN (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@fe4caa4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3906376 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3906376 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3906411 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3906421 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3906430 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3906452 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 3906460 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
[junit4] 2> 3906467 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 3906467 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-002/collection1, trusted=true
[junit4] 2> 3906468 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3906468 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-002/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/init-core-data-001/]
[junit4] 2> 3906470 WARN (coreLoadExecutor-20601-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> 3906559 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3906559 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3906560 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=60.2041015625, floorSegmentMB=2.103515625, forceMergeDeletesPctAllowed=10.129434129661126, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7639175271484967, deletesPctAllowed=45.52058285529316
[junit4] 2> 3906575 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@233960ec[collection1] main]
[junit4] 2> 3906576 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-002/collection1/conf
[junit4] 2> 3906576 INFO (coreLoadExecutor-20601-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3906577 INFO (searcherExecutor-20602-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@233960ec[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.2.0):c4:[diagnostics={java.vendor=Oracle Corporation, os=Mac OS X, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620667394}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3906583 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 3906583 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] 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> 3906583 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3906583 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5ff367ac commitCommandVersion:0
[junit4] 2> 3906587 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6c77fe25[collection1] main]
[junit4] 2> 3906588 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3906589 INFO (searcherExecutor-20602-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6c77fe25[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3906591 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7
[junit4] 2> 3906591 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 3906591 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 3906591 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 3906592 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 3906592 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[xyz]} 0 0
[junit4] 2> 3906592 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 3906592 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 3906592 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 3906592 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 3906592 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3906592 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5ff367ac commitCommandVersion:0
[junit4] 2> 3906600 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@149f519d[collection1] main]
[junit4] 2> 3906601 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3906602 INFO (searcherExecutor-20602-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@149f519d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620667884}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3906602 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 9
[junit4] 2> 3906602 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3906603 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3906603 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3906603 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*,test_nonstored_dv_str&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3906604 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[xyz]} 0 0
[junit4] 2> 3906604 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3906604 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5ff367ac commitCommandVersion:0
[junit4] 2> 3906611 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@701947d5[collection1] main]
[junit4] 2> 3906611 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3906612 INFO (searcherExecutor-20602-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@701947d5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620667895}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3906612 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 8
[junit4] 2> 3906613 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3906613 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 3906613 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] 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> 3906613 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2140785566
[junit4] 2> 3906614 INFO (coreCloseExecutor-20606-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@44338b74
[junit4] 2> 3906614 INFO (coreCloseExecutor-20606-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=44338b74
[junit4] 2> 3906614 INFO (coreCloseExecutor-20606-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@307a4abb: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2e776126
[junit4] 2> 3906625 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 3906625 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b95e3af: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5cb19384
[junit4] 2> 3906633 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 3906633 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60824d10: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@577fb63f
[junit4] 2> 3906636 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 3906636 INFO (TEST-TestUseDocValuesAsStored.testOnEmptyIndex-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@50eedd86: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@408121fa
[junit4] 2> 3906669 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 3906680 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3906682 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.IndexSchema [null] Schema name=test-non-stored-docvalues
[junit4] 2> 3906698 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 3906698 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-003/collection1/conf/managed-schema
[junit4] 2> 3906699 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml to /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-003/collection1/conf/schema-non-stored-docvalues.xml.bak
[junit4] 2> 3906938 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 3906939 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3b42042d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3906939 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3b42042d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3906947 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7756fcbf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3906947 WARN (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7756fcbf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3907006 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3907006 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3907043 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3907054 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3907054 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3907082 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.2.0
[junit4] 2> 3907093 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test-non-stored-docvalues
[junit4] 2> 3907099 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-non-stored-docvalues/1.6 with uniqueid field id
[junit4] 2> 3907099 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-003/collection1, trusted=true
[junit4] 2> 3907100 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f87aa1b
[junit4] 2> 3907100 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-003/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/init-core-data-001/]
[junit4] 2> 3907102 WARN (coreLoadExecutor-20616-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> 3907183 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3907183 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3907184 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=21, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=60.2041015625, floorSegmentMB=2.103515625, forceMergeDeletesPctAllowed=10.129434129661126, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7639175271484967, deletesPctAllowed=45.52058285529316
[junit4] 2> 3907187 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@114c1ea2[collection1] main]
[junit4] 2> 3907187 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.schema.TestUseDocValuesAsStored_B941632FB6121D09-001/tempDir-003/collection1/conf
[junit4] 2> 3907188 INFO (coreLoadExecutor-20616-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3907189 INFO (searcherExecutor-20617-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@114c1ea2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.2.0):c1:[diagnostics={java.vendor=Oracle Corporation, os=Mac OS X, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620667895}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3907197 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 3907197 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907198 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 3907198 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3907198 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b88f45a commitCommandVersion:0
[junit4] 2> 3907268 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e9d4ad4[collection1] main]
[junit4] 2> 3907269 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3907270 INFO (searcherExecutor-20617-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2e9d4ad4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668490}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3907271 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 72
[junit4] 2> 3907271 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907273 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907273 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907274 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907274 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907275 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907276 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 3907276 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3907276 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b88f45a commitCommandVersion:0
[junit4] 2> 3907285 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2b9d1f16[collection1] main]
[junit4] 2> 3907285 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3907285 INFO (searcherExecutor-20617-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2b9d1f16[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668490}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668568}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3907285 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 9
[junit4] 2> 3907285 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_is_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907290 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_is_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907291 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907292 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907293 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:2&fl=test_is_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907294 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=2 status=0 QTime=0
[junit4] 2> 3907304 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 3907304 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3907304 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b88f45a commitCommandVersion:0
[junit4] 2> 3907309 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bdb94a8[collection1] main]
[junit4] 2> 3907314 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3907315 INFO (searcherExecutor-20617-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1bdb94a8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668490}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668568}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668596}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3907315 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 11
[junit4] 2> 3907316 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_ds_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907317 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_ds_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907318 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907319 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907321 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:3&fl=test_ds_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907322 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 3907323 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 3907324 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3907324 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b88f45a commitCommandVersion:0
[junit4] 2> 3907331 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4aa0a814[collection1] main]
[junit4] 2> 3907331 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3907332 INFO (searcherExecutor-20617-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4aa0a814[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668490}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668568}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668596}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668615}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3907332 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 8
[junit4] 2> 3907333 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907333 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907334 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907334 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907335 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:4&fl=test_ls_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907336 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=4 status=0 QTime=0
[junit4] 2> 3907337 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 3907337 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3907337 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b88f45a commitCommandVersion:0
[junit4] 2> 3907344 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3c2e3ac1[collection1] main]
[junit4] 2> 3907344 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3907345 INFO (searcherExecutor-20617-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3c2e3ac1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668490}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668568}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668596}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668615}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668628}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3907345 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 8
[junit4] 2> 3907346 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_fs_dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907347 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_fs_dvo,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907347 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907348 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907349 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:5&fl=test_fs_dvo,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907350 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=5 status=0 QTime=0
[junit4] 2> 3907351 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 3907351 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3907351 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b88f45a commitCommandVersion:0
[junit4] 2> 3907359 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@69fc3cc9[collection1] main]
[junit4] 2> 3907359 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3907361 INFO (searcherExecutor-20617-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@69fc3cc9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668490}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668568}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668596}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668615}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668628}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668643}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3907361 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 9
[junit4] 2> 3907362 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907362 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907363 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:6&fl=*dvo&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907363 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907363 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907364 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=6 status=0 QTime=0
[junit4] 2> 3907365 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 3907365 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3907365 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b88f45a commitCommandVersion:0
[junit4] 2> 3907374 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@427bf8ca[collection1] main]
[junit4] 2> 3907374 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3907375 INFO (searcherExecutor-20617-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@427bf8ca[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668490}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668568}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668596}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668615}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668628}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668643}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_9(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668657}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3907375 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 10
[junit4] 2> 3907376 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_ss_dv&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907377 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_ss_dv,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907378 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907380 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907381 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:7&fl=test_ss_dv,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907382 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=7 status=0 QTime=0
[junit4] 2> 3907383 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 3907384 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3907384 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b88f45a commitCommandVersion:0
[junit4] 2> 3907393 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@23d31625[collection1] main]
[junit4] 2> 3907393 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3907394 INFO (searcherExecutor-20617-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@23d31625[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668490}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668568}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668596}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668615}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668628}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668643}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_9(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668657}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668675}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 3907395 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 10
[junit4] 2> 3907395 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_s_dvo2&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907395 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_s_dvo2,*&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907396 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907396 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] 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> 3907396 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:8&fl=test_s_dvo2,fakeFieldName&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 3907397 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=*&wt=xml} hits=8 status=0 QTime=0
[junit4] 2> 3907398 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 3907398 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3907398 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2b88f45a commitCommandVersion:0
[junit4] 2> 3907404 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@704efd48[collection1] main]
[junit4] 2> 3907404 INFO (TEST-TestUseDocValuesAsStored.testRandomSingleAndMultiValued-seed#[B941632FB6121D09]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3907405 INFO (searcherExecutor-20617-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@704efd48[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668490}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_4(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668568}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_5(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668596}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_6(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668615}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_7(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668628}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_8(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668643}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_9(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668657}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_a(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.version=10.11.6, timestamp=1562620668675}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_b(8.2.0):c1:[diagnostics={os=Mac OS X, java.vendor=Oracle Corporation, java.version=1.8.0_201, java.vm.version=25.201-b09, lucene.version=8.2.0, os.arch=x86_64, java.runtime.version=1.8.0_201-b09, source=flush, os.
[...truncated too long message...]
Master seed: B941632FB6121D09
[junit4] Executing 5 suites with 2 JVMs.
[junit4]
[junit4] Started J1 PID(71424@serv1-vm2.sd-datasolutions.de).
[junit4] Started J0 PID(71425@serv1-vm2.sd-datasolutions.de).
[junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
[junit4] OK 17.0s J1 | TestUseDocValuesAsStored.testMultivaluedOrdering
[junit4] OK 1.46s J1 | TestUseDocValuesAsStored.testOnEmptyIndex
[junit4] OK 7.13s J1 | TestUseDocValuesAsStored.testRandomSingleAndMultiValued
[junit4] OK 1.02s J1 | TestUseDocValuesAsStored.testManagedSchema
[junit4] OK 1.00s J1 | TestUseDocValuesAsStored.testMultipleSearchResults
[junit4] OK 0.98s J1 | TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse
[junit4] OK 1.45s J1 | TestUseDocValuesAsStored.testDuplicateMultiValued
[junit4] Completed [1/5] on J1 in 38.46s, 7 tests
[junit4]
[junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
[junit4] OK 17.3s J0 | TestUseDocValuesAsStored.testMultivaluedOrdering
[junit4] OK 1.86s J0 | TestUseDocValuesAsStored.testOnEmptyIndex
[junit4] OK 6.47s J0 | TestUseDocValuesAsStored.testRandomSingleAndMultiValued
[junit4] OK 0.85s J0 | TestUseDocValuesAsStored.testManagedSchema
[junit4] OK 1.08s J0 | TestUseDocValuesAsStored.testMultipleSearchResults
[junit4] OK 0.90s J0 | TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse
[junit4] OK 1.55s J0 | TestUseDocValuesAsStored.testDuplicateMultiValued
[junit4] Completed [2/5] on J0 in 38.49s, 7 tests
[junit4]
[junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
[junit4] OK 0.81s J0 | TestUseDocValuesAsStored.testMultivaluedOrdering
[junit4] OK 0.69s J0 | TestUseDocValuesAsStored.testOnEmptyIndex
[junit4] OK 4.50s J0 | TestUseDocValuesAsStored.testRandomSingleAndMultiValued
[junit4] OK 1.02s J0 | TestUseDocValuesAsStored.testManagedSchema
[junit4] OK 0.76s J0 | TestUseDocValuesAsStored.testMultipleSearchResults
[junit4] OK 0.86s J0 | TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse
[junit4] OK 0.81s J0 | TestUseDocValuesAsStored.testDuplicateMultiValued
[junit4] Completed [3/5] on J0 in 9.69s, 7 tests
[junit4]
[junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
[junit4] OK 0.73s J1 | TestUseDocValuesAsStored.testMultivaluedOrdering
[junit4] OK 0.76s J1 | TestUseDocValuesAsStored.testOnEmptyIndex
[junit4] OK 4.23s J1 | TestUseDocValuesAsStored.testRandomSingleAndMultiValued
[junit4] OK 0.97s J1 | TestUseDocValuesAsStored.testManagedSchema
[junit4] OK 0.88s J1 | TestUseDocValuesAsStored.testMultipleSearchResults
[junit4] OK 0.77s J1 | TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse
[junit4] OK 1.30s J1 | TestUseDocValuesAsStored.testDuplicateMultiValued
[junit4] Completed [4/5] on J1 in 9.92s, 7 tests
[junit4]
[junit4] Suite: org.apache.solr.schema.TestUseDocValuesAsStored
[junit4] OK 0.32s J0 | TestUseDocValuesAsStored.testMultivaluedOrdering
[junit4] OK 0.82s J0 | TestUseDocValuesAsStored.testOnEmptyIndex
[junit4] OK 3.75s J0 | TestUseDocValuesAsStored.testRandomSingleAndMultiValued
[junit4] OK 0.99s J0 | TestUseDocValuesAsStored.testManagedSchema
[junit4] OK 0.88s J0 | TestUseDocValuesAsStored.testMultipleSearchResults
[junit4] OK 0.67s J0 | TestUseDocValuesAsStored.testUseDocValuesAsStoredFalse
[junit4] OK 1.24s J0 | TestUseDocValuesAsStored.testDuplicateMultiValued
[junit4] Completed [5/5] on J0 in 8.78s, 7 tests
[junit4]
[junit4] JVM J0: 1.04 .. 60.18 = 59.14s
[junit4] JVM J1: 1.03 .. 51.61 = 50.58s
[junit4] Execution time total: 1 minute
[junit4] Tests summary: 5 suites, 35 tests
[junit4] Could not remove temporary path: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp])
[junit4] Could not remove temporary path: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1 (java.nio.file.DirectoryNotEmptyException: Remaining files: [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp])
[echo] 5 slowest tests:
[junit4:tophints] 294.41s | org.apache.solr.cloud.api.collections.ShardSplitTest
[junit4:tophints] 267.99s | org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4:tophints] 183.69s | org.apache.solr.search.facet.TestJsonFacets
[junit4:tophints] 139.06s | org.apache.solr.cloud.CollectionsAPISolrJTest
[junit4:tophints] 121.80s | org.apache.solr.cloud.ReindexCollectionTest
-check-totals:
test-nocompile:
BUILD SUCCESSFUL
Total time: 1 minute 4 seconds
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.schema.TestUseDocValuesAsStored
[repro] Exiting with code 0
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2805)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2684)
at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2665)
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3052)
Also: hudson.remoting.Channel$CallSiteStackTrace: Remote call to MacOSX VBOX
at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1743)
at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
at hudson.remoting.Channel.call(Channel.java:957)
at hudson.FilePath.act(FilePath.java:1072)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2663)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:248)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:79)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:741)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1843)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3054)
at hudson.remoting.UserRequest.perform(UserRequest.java:212)
at hudson.remoting.UserRequest.perform(UserRequest.java:54)
at hudson.remoting.Request$2.run(Request.java:369)
at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
at hudson.FilePath.act(FilePath.java:1074)
at hudson.FilePath.act(FilePath.java:1061)
at hudson.FilePath.validateAntFileMask(FilePath.java:2663)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:248)
at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:79)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:741)
at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
at hudson.model.Build$BuildExecution.post2(Build.java:186)
at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
at hudson.model.Run.execute(Run.java:1843)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:97)
at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/*_pid*.log". Configuration error?
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=4fd1850d2ee2976efe4e1ee5645d32dc394714b1, workspace=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX
[WARNINGS] Computing warning deltas based on reference build #230
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk1.8.0) - Build # 232 -
Failure!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/232/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseParallelGC
All tests passed
Build Log:
[...truncated 14179 lines...]
# A fatal error has been detected by the Java Runtime Environment:
#
# SIGFPE (0x8) at pc=0x00007fff8812d143, pid=80638, tid=0x000000000000628f
#
# JRE version: Java(TM) SE Runtime Environment (8.0_201-b09) (build 1.8.0_201-b09)
# Java VM: Java HotSpot(TM) 64-Bit Server VM (25.201-b09 mixed mode bsd-amd64 compressed oops)
# Problematic frame:
# C [libsystem_kernel.dylib+0x11143] __commpage_gettimeofday+0x43
#
# Failed to write core dump. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
#
# An error report file with more information is saved as:
# /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/hs_err_pid80638.log
Compiled method (nm) 3550946 10997 n 0 java.lang.Thread::sleep (native)
total in heap [0x0000000103c9ef50,0x0000000103c9f278] = 808
relocation [0x0000000103c9f078,0x0000000103c9f0b8] = 64
main code [0x0000000103c9f0c0,0x0000000103c9f278] = 440
#
# If you would like to submit a bug report, please visit:
# http://bugreport.java.com/bugreport/crash.jsp
#
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/Users/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2