You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/05/03 02:03:38 UTC
[JENKINS] Lucene-Solr-Tests-6.x - Build # 180 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/180/
1 tests failed.
FAILED: org.apache.solr.search.TestSolr4Spatial2.testRptWithGeometryField
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([11E30B2D68347860:37F4B3DB55652102]:0)
at org.apache.lucene.util.DocIdSetBuilder.<init>(DocIdSetBuilder.java:92)
at org.apache.lucene.spatial.composite.IntersectsRPTVerifyQuery$IntersectsDifferentiatingQuery$IntersectsDifferentiatingVisitor.<init>(IntersectsRPTVerifyQuery.java:166)
at org.apache.lucene.spatial.composite.IntersectsRPTVerifyQuery$IntersectsDifferentiatingQuery.compute(IntersectsRPTVerifyQuery.java:157)
at org.apache.lucene.spatial.composite.IntersectsRPTVerifyQuery$1.scorer(IntersectsRPTVerifyQuery.java:95)
at org.apache.lucene.search.Weight.bulkScorer(Weight.java:135)
at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:666)
at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:473)
at org.apache.solr.search.SolrIndexSearcher.buildAndRunCollectorChain(SolrIndexSearcher.java:260)
at org.apache.solr.search.SolrIndexSearcher.getDocListNC(SolrIndexSearcher.java:1810)
at org.apache.solr.search.SolrIndexSearcher.getDocListC(SolrIndexSearcher.java:1627)
at org.apache.solr.search.SolrIndexSearcher.search(SolrIndexSearcher.java:643)
at org.apache.solr.handler.component.QueryComponent.process(QueryComponent.java:529)
at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:293)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:155)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2015)
at org.apache.solr.util.TestHarness.query(TestHarness.java:310)
at org.apache.solr.util.TestHarness.query(TestHarness.java:292)
at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:852)
at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:821)
at org.apache.solr.search.TestSolr4Spatial2.testRptWithGeometryField(TestSolr4Spatial2.java:139)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11394 lines...]
[junit4] Suite: org.apache.solr.search.TestSolr4Spatial2
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001
[junit4] 2> 1242958 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1242959 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1242959 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1242959 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1242959 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr
[junit4] 2> 1242959 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1242959 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1242980 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1242983 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 1242988 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-spatial.xml
[junit4] 2> 1242991 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1243000 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1243000 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1243000 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr
[junit4] 2> 1243000 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr'
[junit4] 2> 1243000 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1243000 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr
[junit4] 2> 1243000 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.CoreContainer New CoreContainer 1470250142
[junit4] 2> 1243000 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr]
[junit4] 2> 1243001 WARN (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/lib
[junit4] 2> 1243001 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1243002 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 1243003 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1243003 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1243003 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1243003 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1243004 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig-spatial.xml, transient=false, schema=schema-spatial.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001, shard=shard1}
[junit4] 2> 1243004 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1243004 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1243004 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr
[junit4] 2> 1243004 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1243004 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1243021 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1243024 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 1243029 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-spatial.xml
[junit4] 2> 1243031 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1243040 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1243041 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1243042 INFO (coreLoadExecutor-2040-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1
[junit4] 2> 1243042 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1243042 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001/]
[junit4] 2> 1243042 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1243043 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001
[junit4] 2> 1243043 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001/index/
[junit4] 2> 1243043 WARN (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 1243044 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001/index
[junit4] 2> 1243044 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=69.1103515625, floorSegmentMB=0.8369140625, forceMergeDeletesPctAllowed=10.893127393948243, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1243044 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7985ea90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@57f44f58),segFN=segments_1,generation=1}
[junit4] 2> 1243044 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1243045 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001/
[junit4] 2> 1243053 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1243055 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1243056 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1243057 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1243057 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1243059 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file
[junit4] 2> 1243059 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1243059 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1243059 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1243060 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1505657024, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1243060 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7985ea90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@57f44f58),segFN=segments_1,generation=1}
[junit4] 2> 1243060 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1243060 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@340213d0[collection1] main]
[junit4] 2> 1243061 WARN (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1243061 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1243061 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1243061 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1243061 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1243061 INFO (coreLoadExecutor-2040-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1243062 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1243064 INFO (searcherExecutor-2041-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@340213d0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1243064 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testConstantScoreQueryWithFilterPartOnly
[junit4] 2> 1243064 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1243065 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1243067 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType srptgeom{class=org.apache.solr.schema.SpatialRecursivePrefixTreeFieldType,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args=null} strat: RecursivePrefixTreeStrategy(SPG:(GeohashPrefixTree(maxLevels:11,ctx:SpatialContext{geo=true, calculator=Haversine, worldBounds=Rect(minX=-180.0,maxX=180.0,minY=-90.0,maxY=90.0)})),pruneLeafyBranches) maxLevels: 11
[junit4] 2> 1243075 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 8
[junit4] 2> 1243075 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1243077 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7985ea90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@57f44f58),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7985ea90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@57f44f58),segFN=segments_2,generation=2}
[junit4] 2> 1243078 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 1243079 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@762501cf[collection1] main]
[junit4] 2> 1243080 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1243080 INFO (searcherExecutor-2041-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@762501cf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.1.0):C1)))}
[junit4] 2> 1243080 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 1243093 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType srptgeom{class=org.apache.solr.schema.SpatialRecursivePrefixTreeFieldType,analyzer=org.apache.solr.schema.FieldType$DefaultAnalyzer,args=null} strat: RecursivePrefixTreeStrategy(SPG:(GeohashPrefixTree(maxLevels:11,ctx:SpatialContext{geo=true, calculator=Haversine, worldBounds=Rect(minX=-180.0,maxX=180.0,minY=-90.0,maxY=90.0)})),pruneLeafyBranches) maxLevels: 11
[junit4] 2> 1243136 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!geofilt+sfield%3D"srptgeom"+pt%3D"56.9484,24.0981"+d%3D100}&hl=true&hl.fl=srptgeom} hits=1 status=0 QTime=55
[junit4] 2> 1243137 INFO (TEST-TestSolr4Spatial2.testConstantScoreQueryWithFilterPartOnly-seed#[11E30B2D68347860]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testConstantScoreQueryWithFilterPartOnly
[junit4] 2> 1243144 INFO (TEST-TestSolr4Spatial2.testBadScoreParam-seed#[11E30B2D68347860]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBadScoreParam
[junit4] 2> 1243144 INFO (TEST-TestSolr4Spatial2.testBadScoreParam-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1243145 INFO (TEST-TestSolr4Spatial2.testBadScoreParam-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1243150 INFO (TEST-TestSolr4Spatial2.testBadScoreParam-seed#[11E30B2D68347860]) [ ] o.a.s.h.RequestHandlerBase Ignoring exception matching .
[junit4] 2> 1243150 INFO (TEST-TestSolr4Spatial2.testBadScoreParam-seed#[11E30B2D68347860]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!field+f%3Dbbox+filter%3Dfalse+score%3Dbogus}Intersects(ENVELOPE(0,0,12,12))&qt=standard&start=0&rows=20&version=2.2} status=400 QTime=5
[junit4] 2> 1243150 INFO (TEST-TestSolr4Spatial2.testBadScoreParam-seed#[11E30B2D68347860]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBadScoreParam
[junit4] 2> 1243152 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBBox
[junit4] 2> 1243153 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1243153 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1243154 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 1243160 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 6
[junit4] 2> 1243162 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 1243162 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1243167 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7985ea90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@57f44f58),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7985ea90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@57f44f58),segFN=segments_3,generation=3}
[junit4] 2> 1243167 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 1243169 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@61289c85[collection1] main]
[junit4] 2> 1243169 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1243170 INFO (searcherExecutor-2041-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@61289c85[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.1.0):C3)))}
[junit4] 2> 1243170 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7
[junit4] 2> 1243176 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!field+f%3Dbbox+filter%3Dfalse+score%3DoverlapRatio+queryTargetProportion%3D0.25}Intersects(ENVELOPE(10,25,12,10))&debug=results&fl=*,score&wt=xml} hits=3 status=0 QTime=5
[junit4] 2> 1243177 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!field+f%3Dbbox+filter%3Dfalse+score%3DoverlapRatio+queryTargetProportion%3D0.5+minSideLength%3D1}Intersects(ENVELOPE(0,0,12,12))&debug=results&fl=id,score&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 1243179 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!field+f%3Dbbox+filter%3Dfalse+score%3Darea2D}Intersects(ENVELOPE(0,0,12,12))&debug=results&fl=id,score&wt=xml} hits=3 status=0 QTime=2
[junit4] 2> 1243180 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!field+f%3Dbbox+filter%3Dfalse+score%3Darea}Intersects(ENVELOPE(0,0,12,12))&debug=results&fl=id,score&wt=xml} hits=3 status=0 QTime=0
[junit4] 2> 1243180 INFO (TEST-TestSolr4Spatial2.testBBox-seed#[11E30B2D68347860]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBBox
[junit4] 2> 1243182 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRptWithGeometryField
[junit4] 2> 1243183 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1243183 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1243184 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 1
[junit4] 2> 1243191 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 6
[junit4] 2> 1243191 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1243192 WARN (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 Starting optimize... Reading and rewriting the entire index! Use with care.
[junit4] 2> 1243197 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7985ea90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@57f44f58),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7985ea90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@57f44f58),segFN=segments_4,generation=4}
[junit4] 2> 1243197 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 4
[junit4] 2> 1243199 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@45010b86[collection1] main]
[junit4] 2> 1243199 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1243199 INFO (searcherExecutor-2041-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@45010b86[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.1.0):C2)))}
[junit4] 2> 1243199 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{optimize=} 0 7
[junit4] 2> 1243200 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1243200 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1243200 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.c.SolrCore SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 1243200 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1243200 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 1243205 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+field+f%3Dsrptgeom}Intersects(ENVELOPE(-20,+-10.0001,+30,+15.0001))&sort=id+asc&wt=xml} hits=1 status=0 QTime=4
[junit4] 2> 1243210 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+field+f%3Dsrptgeom}Intersects(ENVELOPE(-20,+-10.0001,+30,+15.0001))&sort=id+asc&wt=xml} hits=1 status=0 QTime=9
[junit4] 2> 1243211 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 1243212 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1243214 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7985ea90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@57f44f58),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7985ea90 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@57f44f58),segFN=segments_5,generation=5}
[junit4] 2> 1243214 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 5
[junit4] 2> 1243214 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@39e7d902[collection1] main]
[junit4] 2> 1243214 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1243214 INFO (searcherExecutor-2041-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@39e7d902[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.1.0):C2) Uninverting(_3(6.1.0):C1)))}
[junit4] 2> 1243215 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 1243217 ERROR (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.h.RequestHandlerBase java.lang.NullPointerException
[junit4] 2> at org.apache.lucene.util.DocIdSetBuilder.<init>(DocIdSetBuilder.java:92)
[junit4] 2> at org.apache.lucene.spatial.composite.IntersectsRPTVerifyQuery$IntersectsDifferentiatingQuery$IntersectsDifferentiatingVisitor.<init>(IntersectsRPTVerifyQuery.java:166)
[junit4] 2> at org.apache.lucene.spatial.composite.IntersectsRPTVerifyQuery$IntersectsDifferentiatingQuery.compute(IntersectsRPTVerifyQuery.java:157)
[junit4] 2> at org.apache.lucene.spatial.composite.IntersectsRPTVerifyQuery$1.scorer(IntersectsRPTVerifyQuery.java:95)
[junit4] 2> at org.apache.lucene.search.Weight.bulkScorer(Weight.java:135)
[junit4] 2> at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:666)
[junit4] 2> at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:473)
[junit4] 2> at org.apache.solr.search.SolrIndexSearcher.buildAndRunCollectorChain(SolrIndexSearcher.java:260)
[junit4] 2> at org.apache.solr.search.SolrIndexSearcher.getDocListNC(SolrIndexSearcher.java:1810)
[junit4] 2> at org.apache.solr.search.SolrIndexSearcher.getDocListC(SolrIndexSearcher.java:1627)
[junit4] 2> at org.apache.solr.search.SolrIndexSearcher.search(SolrIndexSearcher.java:643)
[junit4] 2> at org.apache.solr.handler.component.QueryComponent.process(QueryComponent.java:529)
[junit4] 2> at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:293)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:155)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2015)
[junit4] 2> at org.apache.solr.util.TestHarness.query(TestHarness.java:310)
[junit4] 2> at org.apache.solr.util.TestHarness.query(TestHarness.java:292)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:852)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:821)
[junit4] 2> at org.apache.solr.search.TestSolr4Spatial2.testRptWithGeometryField(TestSolr4Spatial2.java:139)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 1243217 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q={!cache%3Dfalse+field+f%3Dsrptgeom}Intersects(ENVELOPE(-20,+-10.0001,+30,+15.0001))&sort=id+asc&wt=xml} status=500 QTime=16
[junit4] 2> 1243217 ERROR (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q={!cache%3Dfalse+field+f%3Dsrptgeom}Intersects(ENVELOPE(-20,+-10.0001,+30,+15.0001))&sort=id+asc&wt=xml
[junit4] 2> 1243217 INFO (TEST-TestSolr4Spatial2.testRptWithGeometryField-seed#[11E30B2D68347860]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRptWithGeometryField
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolr4Spatial2 -Dtests.method=testRptWithGeometryField -Dtests.seed=11E30B2D68347860 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=da-DK -Dtests.timezone=Etc/GMT-11 -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.04s J2 | TestSolr4Spatial2.testRptWithGeometryField <<<
[junit4] > Throwable #1: java.lang.NullPointerException
[junit4] > at __randomizedtesting.SeedInfo.seed([11E30B2D68347860:37F4B3DB55652102]:0)
[junit4] > at org.apache.lucene.util.DocIdSetBuilder.<init>(DocIdSetBuilder.java:92)
[junit4] > at org.apache.lucene.spatial.composite.IntersectsRPTVerifyQuery$IntersectsDifferentiatingQuery$IntersectsDifferentiatingVisitor.<init>(IntersectsRPTVerifyQuery.java:166)
[junit4] > at org.apache.lucene.spatial.composite.IntersectsRPTVerifyQuery$IntersectsDifferentiatingQuery.compute(IntersectsRPTVerifyQuery.java:157)
[junit4] > at org.apache.lucene.spatial.composite.IntersectsRPTVerifyQuery$1.scorer(IntersectsRPTVerifyQuery.java:95)
[junit4] > at org.apache.lucene.search.Weight.bulkScorer(Weight.java:135)
[junit4] > at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:666)
[junit4] > at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:473)
[junit4] > at org.apache.solr.search.SolrIndexSearcher.buildAndRunCollectorChain(SolrIndexSearcher.java:260)
[junit4] > at org.apache.solr.search.SolrIndexSearcher.getDocListNC(SolrIndexSearcher.java:1810)
[junit4] > at org.apache.solr.search.SolrIndexSearcher.getDocListC(SolrIndexSearcher.java:1627)
[junit4] > at org.apache.solr.search.SolrIndexSearcher.search(SolrIndexSearcher.java:643)
[junit4] > at org.apache.solr.handler.component.QueryComponent.process(QueryComponent.java:529)
[junit4] > at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:293)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:155)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2015)
[junit4] > at org.apache.solr.util.TestHarness.query(TestHarness.java:310)
[junit4] > at org.apache.solr.util.TestHarness.query(TestHarness.java:292)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:852)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:821)
[junit4] > at org.apache.solr.search.TestSolr4Spatial2.testRptWithGeometryField(TestSolr4Spatial2.java:139)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1243219 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1243219 INFO (SUITE-TestSolr4Spatial2-seed#[11E30B2D68347860]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1470250142
[junit4] 2> 1243220 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4191b4bf
[junit4] 2> 1243220 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=1,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=7,cumulative_deletesById=0,cumulative_deletesByQuery=4,cumulative_errors=0}
[junit4] 2> 1243220 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1243220 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1243220 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1243221 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1243221 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1243221 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001 [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001;done=false>>]
[junit4] 2> 1243222 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1243222 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001
[junit4] 2> 1243222 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001/index;done=false>>]
[junit4] 2> 1243222 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 1243222 INFO (coreCloseExecutor-2045-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001/init-core-data-001/index
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestSolr4Spatial2_11E30B2D68347860-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {srptgeom=BlockTreeOrds(blocksize=128), bbox__xdl=PostingsFormat(name=LuceneVarGapFixedInterval), bbox__maxX=PostingsFormat(name=LuceneVarGapFixedInterval), bbox__maxY=PostingsFormat(name=Asserting), bbox__minX=BlockTreeOrds(blocksize=128), bbox__minY=PostingsFormat(name=Memory doPackFST= true), id=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{srptgeom=DocValuesFormat(name=Direct), bbox__maxX=DocValuesFormat(name=Asserting), bbox__maxY=DocValuesFormat(name=Memory), bbox__minX=DocValuesFormat(name=Direct), bbox__minY=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=1954, maxMBSortInHeap=5.155075884741978, sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=da-DK, timezone=Etc/GMT-11
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=180719920,total=425197568
[junit4] 2> NOTE: All tests run in this JVM: [TestManagedSchemaAPI, TestSuggestSpellingConverter, TestObjectReleaseTracker, ScriptEngineTest, TestPerFieldSimilarity, SpatialHeatmapFacetsTest, ConnectionManagerTest, TermVectorComponentDistributedTest, WrapperMergePolicyFactoryTest, BadComponentTest, TestCharFilters, TestConfigSetsAPIExclusivity, TestRestoreCore, TestExtendedDismaxParser, CdcrRequestHandlerTest, SolrCoreCheckLockOnStartupTest, FieldAnalysisRequestHandlerTest, RecoveryZkTest, LeaderFailoverAfterPartitionTest, BlockJoinFacetRandomTest, SmileWriterTest, AddSchemaFieldsUpdateProcessorFactoryTest, ClusterStateTest, TestCSVResponseWriter, TestCloudPivotFacet, CursorMarkTest, TestAuthorizationFramework, ShardSplitTest, DateMathParserTest, TestTrackingShardHandlerFactory, CoreMergeIndexesAdminHandlerTest, TestWriterPerf, SuggesterWFSTTest, TestScoreJoinQPNoScore, TestSolrQueryParser, TestQueryWrapperFilter, LeaderElectionTest, RemoteQueryErrorTest, CloneFieldUpdateProcessorFactoryTest, RollingRestartTest, TestFieldTypeCollectionResource, BinaryUpdateRequestHandlerTest, BitVectorTest, TestLeaderElectionZkExpiry, TestRestManager, SimpleFacetsTest, CachingDirectoryFactoryTest, TestBulkSchemaConcurrent, ParsingFieldUpdateProcessorsTest, VMParamsZkACLAndCredentialsProvidersTest, TestSchemaResource, OverriddenZkACLAndCredentialsProvidersTest, TestStressReorder, TestAnalyzeInfixSuggestions, TestDynamicFieldResource, TestSolr4Spatial2]
[junit4] Completed [261/593 (1!)] on J2 in 0.28s, 4 tests, 1 error <<< FAILURES!
[...truncated 1054 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:740: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/common-build.xml:529: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1014: There were test failures: 593 suites (10 ignored), 2401 tests, 1 error, 85 ignored (70 assumptions) [seed: 11E30B2D68347860]
Total time: 65 minutes 16 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-Tests-6.x - Build # 181 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/181/
1 tests failed.
FAILED: org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([A620C2B97E53C932:374B80B13C776F4E]:0)
at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:159)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:151)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10685 lines...]
[junit4] Suite: org.apache.solr.store.hdfs.HdfsDirectoryTest
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_A620C2B97E53C932-001/init-core-data-001
[junit4] 2> 400888 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 401506 WARN (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your platform... using builtin-java classes where applicable
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 402114 WARN (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 402297 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog
[junit4] 2> 402306 WARN (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 402379 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 402425 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_34496_hdfs____elemey/webapp
[junit4] 2> 402565 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 403255 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:34496
[junit4] 2> 404015 WARN (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 404021 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 404033 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_52731_datanode____l5u4n3/webapp
[junit4] 2> 404129 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 404473 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:52731
[junit4] 2> 404588 WARN (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 404590 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 404610 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_39478_datanode____.btft0q/webapp
[junit4] 2> 404724 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 405042 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:39478
[junit4] 2> 405387 INFO (IPC Server handler 5 on 54682) [ ] BlockStateChange BLOCK* processReport: from storage DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684 node DatanodeRegistration(127.0.0.1, datanodeUuid=c2edfbd3-dd92-4b43-838e-a6acbb089094, infoPort=39478, ipcPort=41159, storageInfo=lv=-56;cid=testClusterID;nsid=2135170501;c=0), blocks: 0, hasStaleStorages: true, processing time: 1 msecs
[junit4] 2> 405387 INFO (IPC Server handler 8 on 54682) [ ] BlockStateChange BLOCK* processReport: from storage DS-5603b68e-4255-4f6b-80d6-983fd925ff80 node DatanodeRegistration(127.0.0.1, datanodeUuid=035ef830-1f44-4f1a-b40f-92005bf92b16, infoPort=52731, ipcPort=39767, storageInfo=lv=-56;cid=testClusterID;nsid=2135170501;c=0), blocks: 0, hasStaleStorages: true, processing time: 3 msecs
[junit4] 2> 405387 INFO (IPC Server handler 5 on 54682) [ ] BlockStateChange BLOCK* processReport: from storage DS-45812904-9936-40d5-a73b-92ed4a1fc1f7 node DatanodeRegistration(127.0.0.1, datanodeUuid=c2edfbd3-dd92-4b43-838e-a6acbb089094, infoPort=39478, ipcPort=41159, storageInfo=lv=-56;cid=testClusterID;nsid=2135170501;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs
[junit4] 2> 405388 INFO (IPC Server handler 8 on 54682) [ ] BlockStateChange BLOCK* processReport: from storage DS-c2f8f3ba-7135-44d8-bc2a-2518d9477571 node DatanodeRegistration(127.0.0.1, datanodeUuid=035ef830-1f44-4f1a-b40f-92005bf92b16, infoPort=52731, ipcPort=39767, storageInfo=lv=-56;cid=testClusterID;nsid=2135170501;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs
[junit4] 2> 405948 INFO (TEST-HdfsDirectoryTest.testWritingAndReadingAFile-seed#[A620C2B97E53C932]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWritingAndReadingAFile
[junit4] 2> 406252 INFO (IPC Server handler 9 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741825_1001{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-45812904-9936-40d5-a73b-92ed4a1fc1f7:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|RBW]]} size 4
[junit4] 2> 406252 INFO (IPC Server handler 0 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741825_1001 size 4
[junit4] 2> 406726 INFO (IPC Server handler 1 on 54682) [ ] BlockStateChange BLOCK* addToInvalidates: blk_1073741825_1001 127.0.0.1:42497 127.0.0.1:57598
[junit4] 2> 406731 INFO (TEST-HdfsDirectoryTest.testWritingAndReadingAFile-seed#[A620C2B97E53C932]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWritingAndReadingAFile
[junit4] 2> 406754 INFO (TEST-HdfsDirectoryTest.testEOF-seed#[A620C2B97E53C932]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEOF
[junit4] 2> 406821 INFO (IPC Server handler 7 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741826_1002{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-c2f8f3ba-7135-44d8-bc2a-2518d9477571:NORMAL:127.0.0.1:42497|FINALIZED]]} size 0
[junit4] 2> 406826 INFO (IPC Server handler 0 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741826_1002{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-c2f8f3ba-7135-44d8-bc2a-2518d9477571:NORMAL:127.0.0.1:42497|FINALIZED], ReplicaUnderConstruction[[DISK]DS-45812904-9936-40d5-a73b-92ed4a1fc1f7:NORMAL:127.0.0.1:57598|FINALIZED]]} size 0
[junit4] 2> 406834 INFO (TEST-HdfsDirectoryTest.testEOF-seed#[A620C2B97E53C932]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEOF
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HdfsDirectoryTest -Dtests.method=testEOF -Dtests.seed=A620C2B97E53C932 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=fr-LU -Dtests.timezone=Pacific/Port_Moresby -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.11s J2 | HdfsDirectoryTest.testEOF <<<
[junit4] > Throwable #1: java.lang.NullPointerException
[junit4] > at __randomizedtesting.SeedInfo.seed([A620C2B97E53C932:374B80B13C776F4E]:0)
[junit4] > at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
[junit4] > at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:159)
[junit4] > at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:151)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 406866 INFO (TEST-HdfsDirectoryTest.testRename-seed#[A620C2B97E53C932]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRename
[junit4] 2> 406903 INFO (IPC Server handler 7 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741827_1003{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-45812904-9936-40d5-a73b-92ed4a1fc1f7:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|FINALIZED]]} size 0
[junit4] 2> 406905 INFO (IPC Server handler 0 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741827_1003{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|FINALIZED], ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|FINALIZED]]} size 0
[junit4] 2> 406921 INFO (IPC Server handler 5 on 54682) [ ] BlockStateChange BLOCK* addToInvalidates: blk_1073741827_1003 127.0.0.1:57598 127.0.0.1:42497
[junit4] 2> 406923 INFO (TEST-HdfsDirectoryTest.testRename-seed#[A620C2B97E53C932]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRename
[junit4] 2> 406946 INFO (TEST-HdfsDirectoryTest.testRandomAccessWrites-seed#[A620C2B97E53C932]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomAccessWrites
[junit4] 1> Working on pass [0] contains [false]
[junit4] 2> 407135 INFO (IPC Server handler 3 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741828_1004{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-45812904-9936-40d5-a73b-92ed4a1fc1f7:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-c2f8f3ba-7135-44d8-bc2a-2518d9477571:NORMAL:127.0.0.1:42497|FINALIZED]]} size 0
[junit4] 2> 407136 INFO (IPC Server handler 6 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741828_1004{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-45812904-9936-40d5-a73b-92ed4a1fc1f7:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-c2f8f3ba-7135-44d8-bc2a-2518d9477571:NORMAL:127.0.0.1:42497|FINALIZED]]} size 0
[junit4] 1> Working on pass [1] contains [false]
[junit4] 2> 408230 INFO (IPC Server handler 0 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741829_1005{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|RBW], ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW]]} size 0
[junit4] 2> 408230 INFO (IPC Server handler 9 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741829_1005{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|RBW], ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW]]} size 0
[junit4] 1> Working on pass [2] contains [false]
[junit4] 2> 412277 INFO (IPC Server handler 3 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741830_1006{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-c2f8f3ba-7135-44d8-bc2a-2518d9477571:NORMAL:127.0.0.1:42497|RBW]]} size 0
[junit4] 2> 412278 INFO (IPC Server handler 6 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741830_1006{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-c2f8f3ba-7135-44d8-bc2a-2518d9477571:NORMAL:127.0.0.1:42497|RBW], ReplicaUnderConstruction[[DISK]DS-45812904-9936-40d5-a73b-92ed4a1fc1f7:NORMAL:127.0.0.1:57598|FINALIZED]]} size 0
[junit4] 1> Working on pass [3] contains [false]
[junit4] 2> 412962 INFO (IPC Server handler 4 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741831_1007{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|RBW]]} size 0
[junit4] 2> 412963 INFO (IPC Server handler 2 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741831_1007{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|RBW]]} size 0
[junit4] 1> Working on pass [4] contains [false]
[junit4] 2> 414970 INFO (IPC Server handler 8 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741832_1008{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-c2f8f3ba-7135-44d8-bc2a-2518d9477571:NORMAL:127.0.0.1:42497|FINALIZED]]} size 0
[junit4] 2> 414971 INFO (IPC Server handler 5 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741832_1008{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-c2f8f3ba-7135-44d8-bc2a-2518d9477571:NORMAL:127.0.0.1:42497|FINALIZED], ReplicaUnderConstruction[[DISK]DS-45812904-9936-40d5-a73b-92ed4a1fc1f7:NORMAL:127.0.0.1:57598|FINALIZED]]} size 0
[junit4] 1> Working on pass [5] contains [false]
[junit4] 2> 415792 INFO (IPC Server handler 1 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741833_1009{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|RBW], ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW]]} size 0
[junit4] 2> 415793 INFO (IPC Server handler 3 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741833_1009{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|RBW], ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW]]} size 0
[junit4] 1> Working on pass [6] contains [false]
[junit4] 2> 416074 INFO (IPC Server handler 4 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741834_1010{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-c2f8f3ba-7135-44d8-bc2a-2518d9477571:NORMAL:127.0.0.1:42497|RBW]]} size 0
[junit4] 2> 416075 INFO (IPC Server handler 2 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741834_1010{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-c2f8f3ba-7135-44d8-bc2a-2518d9477571:NORMAL:127.0.0.1:42497|RBW], ReplicaUnderConstruction[[DISK]DS-45812904-9936-40d5-a73b-92ed4a1fc1f7:NORMAL:127.0.0.1:57598|FINALIZED]]} size 0
[junit4] 1> Working on pass [7] contains [false]
[junit4] 2> 417431 INFO (IPC Server handler 0 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741835_1011{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|RBW], ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|FINALIZED]]} size 0
[junit4] 2> 417432 INFO (IPC Server handler 4 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741835_1011{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|RBW], ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|FINALIZED]]} size 0
[junit4] 1> Working on pass [8] contains [false]
[junit4] 2> 418914 INFO (IPC Server handler 5 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741836_1012{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|RBW], ReplicaUnderConstruction[[DISK]DS-45812904-9936-40d5-a73b-92ed4a1fc1f7:NORMAL:127.0.0.1:57598|RBW]]} size 0
[junit4] 2> 418915 INFO (IPC Server handler 7 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741836_1012 size 5176731
[junit4] 1> Working on pass [9] contains [false]
[junit4] 2> 421576 INFO (IPC Server handler 9 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42497 is added to blk_1073741837_1013{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|FINALIZED]]} size 0
[junit4] 2> 421576 INFO (IPC Server handler 5 on 54682) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57598 is added to blk_1073741837_1013{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9d1eef5f-6af1-4fb6-88e4-a8b7b0546684:NORMAL:127.0.0.1:57598|RBW], ReplicaUnderConstruction[[DISK]DS-5603b68e-4255-4f6b-80d6-983fd925ff80:NORMAL:127.0.0.1:42497|FINALIZED]]} size 0
[junit4] 2> 423980 INFO (TEST-HdfsDirectoryTest.testRandomAccessWrites-seed#[A620C2B97E53C932]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRandomAccessWrites
[junit4] 2> 423983 WARN (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 423990 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 424094 WARN (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_A620C2B97E53C932-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_A620C2B97E53C932-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost/127.0.0.1:54682) [ ] o.a.h.h.s.d.DataNode BPOfferService for Block pool BP-1731288795-10.41.0.5-1462264180862 (Datanode Uuid c2edfbd3-dd92-4b43-838e-a6acbb089094) service to localhost/127.0.0.1:54682 interrupted
[junit4] 2> 424094 WARN (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_A620C2B97E53C932-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_A620C2B97E53C932-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost/127.0.0.1:54682) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1731288795-10.41.0.5-1462264180862 (Datanode Uuid c2edfbd3-dd92-4b43-838e-a6acbb089094) service to localhost/127.0.0.1:54682
[junit4] 2> 424099 WARN (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 424103 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 424206 WARN (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_A620C2B97E53C932-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_A620C2B97E53C932-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost/127.0.0.1:54682) [ ] o.a.h.h.s.d.DataNode BPOfferService for Block pool BP-1731288795-10.41.0.5-1462264180862 (Datanode Uuid 035ef830-1f44-4f1a-b40f-92005bf92b16) service to localhost/127.0.0.1:54682 interrupted
[junit4] 2> 424206 WARN (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_A620C2B97E53C932-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_A620C2B97E53C932-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost/127.0.0.1:54682) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1731288795-10.41.0.5-1462264180862 (Datanode Uuid 035ef830-1f44-4f1a-b40f-92005bf92b16) service to localhost/127.0.0.1:54682
[junit4] 2> 424215 WARN (org.apache.hadoop.hdfs.server.blockmanagement.DecommissionManager$Monitor@40518428) [ ] o.a.h.h.s.b.DecommissionManager Monitor interrupted: java.lang.InterruptedException: sleep interrupted
[junit4] 2> 424231 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 424337 ERROR (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.a.h.m.l.MethodMetric Error invoking method getBlocksTotal
[junit4] 2> java.lang.reflect.InvocationTargetException
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at org.apache.hadoop.metrics2.lib.MethodMetric$2.snapshot(MethodMetric.java:111)
[junit4] 2> at org.apache.hadoop.metrics2.lib.MethodMetric.snapshot(MethodMetric.java:144)
[junit4] 2> at org.apache.hadoop.metrics2.lib.MetricsRegistry.snapshot(MetricsRegistry.java:387)
[junit4] 2> at org.apache.hadoop.metrics2.lib.MetricsSourceBuilder$1.getMetrics(MetricsSourceBuilder.java:79)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMetrics(MetricsSourceAdapter.java:195)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.updateJmxCache(MetricsSourceAdapter.java:172)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMBeanInfo(MetricsSourceAdapter.java:151)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
[junit4] 2> at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592)
[junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
[junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
[junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145)
[junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822)
[junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720)
[junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699)
[junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:198)
[junit4] 2> at org.apache.solr.store.hdfs.HdfsDirectoryTest.afterClass(HdfsDirectoryTest.java:66)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.lang.NullPointerException
[junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198)
[junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291)
[junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223)
[junit4] 2> ... 54 more
[junit4] 2> 424348 INFO (SUITE-HdfsDirectoryTest-seed#[A620C2B97E53C932]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_A620C2B97E53C932-001
[junit4] 2> May 03, 2016 8:30:03 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=fr-LU, timezone=Pacific/Port_Moresby
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=4,free=165018784,total=511180800
[junit4] 2> NOTE: All tests run in this JVM: [TestPostingsSolrHighlighter, SuggesterWFSTTest, TestSuggestSpellingConverter, RankQueryTest, BadIndexSchemaTest, TestMiniSolrCloudCluster, TestXmlQParser, StatelessScriptUpdateProcessorFactoryTest, FieldAnalysisRequestHandlerTest, TestConfigSets, SecurityConfHandlerTest, CollectionStateFormat2Test, TestRequestStatusCollectionAPI, UpdateParamsTest, SimpleFacetsTest, TestMiniSolrCloudClusterSSL, TestSweetSpotSimilarityFactory, TestDistributedMissingSort, CurrencyFieldXmlFileTest, SuggesterTSTTest, LeaderElectionTest, DirectUpdateHandlerOptimizeTest, RecoveryZkTest, NoCacheHeaderTest, HdfsDirectoryTest]
[junit4] Completed [54/594 (1!)] on J2 in 33.27s, 4 tests, 1 error <<< FAILURES!
[...truncated 1768 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:740: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/common-build.xml:529: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1014: There were test failures: 594 suites (10 ignored), 2402 tests, 1 error, 85 ignored (70 assumptions) [seed: A620C2B97E53C932]
Total time: 64 minutes 17 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any