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 2014/03/21 11:12:21 UTC
[JENKINS] Lucene-Solr-Tests-trunk-Java7 - Build # 4662 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-Tests-trunk-Java7/4662/
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.component.BadComponentTest
Error Message:
ERROR: SolrIndexSearcher opens=1 closes=2
Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=1 closes=2
at __randomizedtesting.SeedInfo.seed([1AB0D9793B9437C9]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:420)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:179)
at sun.reflect.GeneratedMethodAccessor26.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1617)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:789)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:359)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 10463 lines...]
[junit4] Suite: org.apache.solr.handler.component.BadComponentTest
[junit4] 2> 409378 T1686 oas.SolrTestCaseJ4.startTrackingSearchers WARN startTrackingSearchers: numOpens=6 numCloses=5
[junit4] 2> 409379 T1686 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 409383 T1686 oas.SolrTestCaseJ4.setUp ###Starting testBadElevate
[junit4] 2> 409385 T1686 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196
[junit4] 2> 409386 T1686 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 409388 T1686 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 409389 T1686 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 409389 T1686 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 409544 T1686 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 409630 T1686 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-elevate.xml
[junit4] 2> 409631 T1686 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 409647 T1686 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 410256 T1686 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 410259 T1686 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 410261 T1686 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 410274 T1686 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 410279 T1686 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 410283 T1686 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 410284 T1686 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr
[junit4] 2> 410284 T1686 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/'
[junit4] 2> 410350 T1686 oasc.CoreContainer.<init> New CoreContainer 573976317
[junit4] 2> 410351 T1686 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/]
[junit4] 2> 410352 T1686 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 410353 T1686 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null
[junit4] 2> 410353 T1686 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 410353 T1686 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 410354 T1686 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 410354 T1686 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 410354 T1686 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 410354 T1686 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 410355 T1686 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 410355 T1686 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 410356 T1686 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 410356 T1686 oasc.CoreContainer.load Host Name:
[junit4] 2> 410361 T1687 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1
[junit4] 2> 410362 T1687 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/'
[junit4] 2> 410362 T1687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 410363 T1687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
[junit4] 2> 410363 T1687 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 410410 T1687 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 410464 T1687 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-elevate.xml
[junit4] 2> 410464 T1687 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 410473 T1687 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 410686 T1586 oasc.Overseer.createOverseerNode ERROR Could not create Overseer node org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:311)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:308)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:308)
[junit4] 2> at org.apache.solr.cloud.Overseer.createOverseerNode(Overseer.java:1175)
[junit4] 2> at org.apache.solr.cloud.Overseer.getInQueue(Overseer.java:1139)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:191)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:164)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:108)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:289)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.rejoinLeaderElection(ElectionContext.java:422)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:212)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:164)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:108)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:289)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.rejoinLeaderElection(ElectionContext.java:422)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:212)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:164)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:108)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2>
[junit4] 2> 410692 T1586 oasc.SolrCore.close [testSolrCloudCollection_shard1_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@11b07e7c
[junit4] 2> 410701 T1586 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=1,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 410702 T1586 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 410703 T1586 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 410703 T1586 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 410703 T1586 oasc.SolrCore.closeSearcher [testSolrCloudCollection_shard1_replica1] Closing main searcher on request.
[junit4] 2> 410704 T1586 oasc.CachingDirectoryFactory.close Closing RAMDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 410704 T1586 oasc.CachingDirectoryFactory.closeCacheValue looking to close data [CachedDir<<refCount=0;path=data;done=false>>]
[junit4] 2> 410704 T1586 oasc.CachingDirectoryFactory.close Closing directory: data
[junit4] 2> 410704 T1586 oasc.CachingDirectoryFactory.closeCacheValue looking to close data/index [CachedDir<<refCount=0;path=data/index;done=false>>]
[junit4] 2> 410704 T1586 oasc.CachingDirectoryFactory.close Closing directory: data/index
[junit4] 2> 410705 T1586 oasc.LeaderElector$1.process WARN java.lang.RuntimeException: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer
[junit4] 2> at org.apache.solr.cloud.Overseer.createOverseerNode(Overseer.java:1184)
[junit4] 2> at org.apache.solr.cloud.Overseer.getInQueue(Overseer.java:1139)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:191)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:164)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:108)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:289)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.rejoinLeaderElection(ElectionContext.java:422)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:212)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:164)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:108)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:289)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.rejoinLeaderElection(ElectionContext.java:422)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:212)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:164)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:108)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:137)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:311)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:308)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:73)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:308)
[junit4] 2> at org.apache.solr.cloud.Overseer.createOverseerNode(Overseer.java:1175)
[junit4] 2> ... 18 more
[junit4] 2>
[junit4] 2> 410712 T1586 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 410712 T1586 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 410712 T1586 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 410712 T1586 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 410713 T1586 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 410713 T1586 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 410713 T1586 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 410713 T1586 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 410713 T1586 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 410713 T1586 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 410929 T1687 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 410932 T1687 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 410934 T1687 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 410944 T1687 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 410948 T1687 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 410951 T1687 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 410951 T1687 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/, dataDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196/
[junit4] 2> 410952 T1687 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1900a31e
[junit4] 2> 410952 T1687 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196
[junit4] 2> 410953 T1687 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196/index/
[junit4] 2> 410953 T1687 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196/index' doesn't exist. Creating new index...
[junit4] 2> 410953 T1687 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196/index
[junit4] 2> 410954 T1687 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=67.265625, floorSegmentMB=0.7822265625, forceMergeDeletesPctAllowed=16.426221393048035, segmentsPerTier=44.0, maxCFSSegmentSizeMB=1.8388671875, noCFSRatio=1.0
[junit4] 2> 410954 T1687 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e58f0e6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@696fbc6),segFN=segments_1,generation=1}
[junit4] 2> 410955 T1687 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 410959 T1687 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 410959 T1687 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 410959 T1687 oasc.RequestHandlers.initHandlersFromConfig created /elevate: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 410960 T1687 oasc.RequestHandlers.initHandlersFromConfig created /dataElevate: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 410973 T1687 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 410973 T1687 oasc.RequestHandlers.initHandlersFromConfig WARN no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 410975 T1687 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 410976 T1687 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [SCHEMA-REPLICATION1.XML, SCHEMA-IB.XML, SOLRCONFIG-DEFAULTS.XML, BAD-SCHEMA-SWEETSPOT-BOTH-TF.XML, BAD-CURRENCY.XML, CONDITIONAL.UPDATEPROCESSOR.JS, BAD-SCHEMA-CURRENCY-MULTIVALUED.XML, SOLRCONFIG-WARMER.XML, SOLRCONFIG-MASTER1.XML, SCHEMA-LMDIRICHLET.XML, SOLRCONFIG-SNIPPET-PROCESSOR.XML, BAD-SCHEMA-DUP-FIELDTYPE.XML, SCHEMA-DOCVALUESMISSING.XML, MAPPING-ISOLATIN1ACCENT.TXT, BAD-SOLRCONFIG-MANAGED-SCHEMA-NAMED-SCHEMA.XML.XML, SCHEMA-SORTS.XML, SOLRCONFIG-BASIC.XML, SOLRCONFIG-NOOPREGEN.XML, ELEVATE.XML, STOPTYPES-2.TXT, SCHEMA-COPYFIELD-TEST.XML, SOLRCONFIG-REPEATER.XML, BAD-SCHEMA-UNIQUEKEY-IS-COPYFIELD-DEST.XML, SCHEMA-SYNONYM-TOKENIZER.XML, SOLRCONFIG-MASTER3.XML, BLENDEDINFIXSUGGEST.TXT, BAD-SOLRCONFIG-MULTIPLE-CFS.XML, HYPHENATION.DTD, SCHEMA-DOCVALUESMULTI.XML, SOLRCONFIG-MERGEPOLICY-DEFAULTS.XML, BAD-SCHEMA-CURRENCY-DYNAMIC-MULTIVALUED.XML, SOLRCONFIG-RESPONSE-LOG-COMPONENT.XML, SCHEMA-XINCLUDE.XML, SOLRCONFIG-PARSING-UPDATE-PROCESSOR-CHAINS.XML, SOLRCONFIG-DELPOLICY1.XML, MISSLEADING.EXTENSION.UPDATEPROCESSOR.JS.TXT, SCHEMA_CODEC.XML, SOLRCONFIG-DELPOLICY2.XML, SOLRCONFIG-POSTINGSHIGHLIGHT.XML, SCHEMA-DFR.XML, SOLRCONFIG-SLAVE1.XML, SOLRCONFIG-IMPLICITPROPERTIES.XML, BAD-SOLRCONFIG-UNEXPECTED-SCHEMA-ATTRIBUTE.XML, SOLRCONFIG-LAZYWRITER.XML, OLD_SYNONYMS.TXT, SOLRCONFIG-QUERYSENDER-NOQUERY.XML, SCHEMA-POSTINGSHIGHLIGHT.XML, SCHEMASURROUND.XML, SOLRCONFIG-INDEXCONFIG.XML, SCHEMA-PHRASESUGGEST.XML, STOP-2.TXT, BAD-SCHEMA-MULTIPLE-ASTERISK-COPYFIELD-DEST-SHOULD-FAIL-TEST.XML, SOLRCONFIG-LOGMERGEPOLICY.XML, SCHEMA-ENUMS.XML, SCHEMA-SNIPPET-TYPES.INCL, STOPWORDS.TXT, SCHEMA-SPELLCHECKER.XML, SCHEMA-NOT-REQUIRED-UNIQUE-KEY.XML, KEEP-1.TXT, SCHEMA-SIM.XML, BAD-SCHEMA-NON-GLOB-COPYFIELD-SOURCE-MATCHING-NOTHING-SHOULD-FAIL-TEST.XML, SCHEMA-SIMPLEQPPLUGIN.XML, SCHEMA-DISTRIBUTED-MISSING-SORT.XML, SOLRCONFIG-MANAGED-SCHEMA.XML, BAD-SOLRCONFIG-BOGUS-SCRIPTENGINE-NAME.XML, SCHEMA-SPATIAL.XML, SOLRCONFIG-HIGHLIGHT.XML, SCHEMA-TFIDF.XML, ANALYZINGINFIXSUGGEST.TXT, PROTWORDS.TXT, BAD-SCHEMA-MISPLACED-ASTERISK-COPYFIELD-DEST-SHOULD-FAIL-TEST.XML, SCHEMA-REST-LUCENE-MATCH-VERSION.XML, SOLRCONFIG-INFOSTREAM-LOGGING.XML, BAD-SCHEMA-UNSUPPORTED-DOCVALUES.XML, BAD-MP-SOLRCONFIG.XML, BAD-SOLRCONFIG-MULTIPLE-DIRFACTORY.XML, SOLRCONFIG-QUERY-PARSER-INIT.XML, BAD-SCHEMA-ANALYZER-CLASS-AND-NESTED.XML, SCHEMA-LUCENEMATCHVERSION.XML, SCHEMA-ANALYTICS.XML, SYNONYMS.TXT, BAD-SCHEMA-CODEC-GLOBAL-VS-FT-MISMATCH.XML, DA_UTF8.XML, BAD-SCHEMA-EXTERNAL-FILEFIELD.XML, BAD-SCHEMA-MISPLACED-ASTERISK-COPYFIELD-SOURCE-SHOULD-FAIL-TEST.XML, COMPOUNDDICTIONARY.TXT, SCHEMA-NUMERIC.XML, BAD-SOLRCONFIG-INVALID-SCRIPTFILE.XML, SCHEMA15.XML, FRENCHARTICLES.TXT, ENUMSCONFIG.XML, SOLRCONFIG-SOLCOREPROPERTIES.XML, SCHEMA-BINARYFIELD.XML, SOLRCONFIG-REQHANDLER.INCL, SOLRCONFIG-DEEPPAGING.XML, SOLRCONFIG-SPELLCHECKCOMPONENT.XML, FUZZYSUGGEST.TXT, SCHEMA-ONE-FIELD-NO-DYNAMIC-FIELD.XML, OPEN-EXCHANGE-RATES.JSON, STOPWITHBOM.TXT, SOLRCONFIG-ADD-SCHEMA-FIELDS-UPDATE-PROCESSOR-CHAINS.XML, BAD-SCHEMA-CURRENCY-FT-BOGUS-DEFAULT-CODE.XML, SCHEMA-ADD-SCHEMA-FIELDS-UPDATE-PROCESSOR.XML, SCHEMA-COLLATE-DV.XML, SCHEMA-CHARFILTERS.XML, SCHEMA-MINIMAL.XML, XSLT, SCHEMA11.XML, PHRASESUGGEST.TXT, SOLRCONFIG-MINIMAL.XML, SCHEMA-FOLDING.XML, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML, TRIVIAL.UPDATEPROCESSOR0.JS, SOLRCONFIG_PERF.XML, MISSING.FUNCTIONS.UPDATEPROCESSOR.JS, SCHEMA-BM25.XML, SCHEMA-CUSTOM-FIELD.XML, SCHEMA-FIELD-SORT-VALUES.XML, SCHEMA-DOCVALUES.XML, SOLRCONFIG-SCRIPT-UPDATEPROCESSOR.XML, .SVN, KEEP-2.TXT, BAD-SCHEMA-DYNAMICFIELD-REQUIRED.XML, HUNSPELL-TEST.AFF, BAD-SCHEMA-NOT-INDEXED-BUT-POS.XML, JASUGGEST.TXT, BAD-SCHEMA-SWEETSPOT-PARTIAL-NORMS.XML, STOPTYPES-1.TXT, SOLRCONFIG-SPELLCHECKER.XML, SCHEMA-REST.XML, SCHEMA-COMPLEX-PHRASE.XML, SOLRCONFIG-ALTDIRECTORY.XML, BAD-SCHEMA-CURRENCY-FT-OER-NORATES.XML, SOLRCONFIG-TIEREDMERGEPOLICY.XML, BAD-SCHEMA-UNIQUEKEY-USES-DEFAULT.XML, SCHEMA-ONE-FIELD-NO-DYNAMIC-FIELD-UNIQUE-KEY.XML, SOLRCONFIG-QUERYSENDER.XML, SOLRCONFIG-EXTERNALVERSIONCONSTRAINT.XML, TRIVIAL.UPDATEPROCESSOR1.JS, SCHEMA-TINY.XML, SCHEMA-STOP-KEEP.XML, BAD-SOLRCONFIG-SCHEMA-MUTABLE-BUT-NOT-MANAGED.XML, SCHEMA-EFF.XML, CURRENCY.XML, SOLRCONFIG-SUGGESTERCOMPONENT.XML, SOLRCONFIG-MASTER2.XML, ADDFIELDS.UPDATEPROCESSOR.JS, SOLRCONFIG-TRANSFORMERS.XML, BAD-SCHEMA-DUP-DYNAMICFIELD.XML, SOLRCONFIG_CODEC.XML, SCHEMA-CLASS-NAME-SHORTENING-ON-SERIALIZATION.XML, SCHEMA-DOCVALUESFACETING.XML, SOLRCONFIG-COMPONENTS-NAME.XML, BAD_SOLRCONFIG.XML, BAD-SCHEMA-DUP-FIELD.XML, THROW.ERROR.ON.ADD.UPDATEPROCESSOR.JS, FREETEXTSUGGEST.TXT, BAD-SCHEMA-DYNAMICFIELD-DEFAULT-VAL.XML, BAD-SCHEMA-CURRENCY-FT-MULTIVALUED.XML, SCHEMA12.XML, SOLRCONFIG-NOCACHE.XML, STOPWORDSWRONGENCODING.TXT, SOLRCONFIG-SOLR-749.XML, SCHEMA-SWEETSPOT.XML, SOLRCONFIG-MASTER.XML, SCHEMA-REPLICATION2.XML, SCHEMA-REVERSED.XML, BAD-SCHEMA-NONTEXT-ANALYZER.XML, HUNSPELL-TEST.DIC, STEMDICT.TXT, BAD-SCHEMA-UNIQUEKEY-MULTIVALUED.XML, SCHEMA-CUSTOMFIELD.XML, BAD-SCHEMA-NOT-INDEXED-BUT-TF.XML, BAD-SCHEMA-OMIT-TF-BUT-NOT-POS.XML, REGEX-BOOST-PROCESSOR-TEST.TXT, STOP-SNOWBALL.TXT, SOLRCONFIG-ELEVATE.XML, SOLRCONFIG-UPDATE-PROCESSOR-CHAINS.XML, SOLRCONFIG-CACHING.XML, BAD-SCHEMA-BOGUS-FIELD-PARAMETERS.XML, SOLRCONFIG-PHRASESUGGEST.XML, BAD-SCHEMA-CURRENCY-FT-BOGUS-CODE-IN-XML.XML, SCHEMA-TRIE.XML, BAD-SCHEMA-SWEETSPOT-PARTIAL-HYPERBOLIC.XML, BAD-SCHEMA-MULTIPLE-ASTERISK-COPYFIELD-SOURCE-SHOULD-FAIL-TEST.XML, BAD-SCHEMA-SIM-GLOBAL-VS-FT-MISMATCH.XML, SCHEMA-SNIPPET-FIELD.XML, BAD-SCHEMA-NOT-INDEXED-BUT-NORMS.XML, STOP-1.TXT, SCHEMA-COLLATE.XML, BAD-SOLRCONFIG-MISSING-SCRIPTFILE.XML, BAD-SCHEMA-SWEETSPOT-PARTIAL-BASELINE.XML, BAD-SCHEMA-BOGUS-ANALYSIS-PARAMETERS.XML, DA_COMPOUNDDICTIONARY.TXT, BAD-SOLRCONFIG-MULTIPLE-INDEXCONFIGS.XML, SOLRCONFIG-TLOG.XML, BAD-SOLRCONFIG-WARMER-NO-REOPEN.XML, SCHEMA-BEHAVIOR.XML, SOLRCONFIG-COLLAPSEQPARSER.XML, SOLRCONFIG-MASTER1-KEEPONEBACKUP.XML, SOLRCONFIG-MERGEPOLICY-LEGACY.XML, SOLRCONFIG-FUNCTIONQUERY.XML, SCHEMA-REQUIRED-FIELDS.XML, SOLRCONFIG-XINCLUDE.XML, SCHEMA-ID-AND-VERSION-FIELDS-ONLY.XML, SOLRCONFIG-SLAVE.XML, SCHEMA-LMJELINEKMERCER.XML, WDFTYPES.TXT, SOLRCONFIG-TEST-MISC.XML, SCHEMA-SNIPPET-TYPE.XML]
[junit4] 2> 410979 T1687 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 410979 T1687 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 410980 T1687 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=681881830, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 410980 T1687 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1e58f0e6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@696fbc6),segFN=segments_1,generation=1}
[junit4] 2> 410981 T1687 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 410981 T1687 oass.SolrIndexSearcher.<init> Opening Searcher@3bb633cd[collection1] main
[junit4] 2> 410981 T1687 oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage initialized to use dir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf
[junit4] 2> 410982 T1687 oasr.RestManager.init Initializing RestManager with initArgs: {storageDir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf/}
[junit4] 2> 410982 T1687 oasr.ManagedResourceStorage.load Reading _rest_managed.json using file:dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/core/src/test-files/solr/collection1/conf
[junit4] 2> 410983 T1687 oasr.ManagedResource.reloadFromStorage WARN No stored data found for /rest/managed
[junit4] 2> 410983 T1687 oasr.ManagedResource.notifyObserversDuringInit WARN No registered observers for /rest/managed
[junit4] 2> 410983 T1687 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 410984 T1687 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@49c1a92d
[junit4] 2> 410985 T1688 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3bb633cd[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 410990 T1687 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 410990 T1687 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 410990 T1687 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 410990 T1687 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 410991 T1687 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 410991 T1687 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 410991 T1687 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196 [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196;done=false>>]
[junit4] 2> 410991 T1687 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196
[junit4] 2> 410992 T1687 oasc.CachingDirectoryFactory.closeCacheValue looking to close /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196/index [CachedDir<<refCount=0;path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196/index;done=false>>]
[junit4] 2> 410992 T1687 oasc.CachingDirectoryFactory.close Closing directory: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build/solr-core/test/J0/./solrtest-BadComponentTest-1395394026196/index
[junit4] 2> 410992 T1687 oasc.CoreContainer.recordAndThrow ERROR Unable to create core: collection1 Ignoring exception matching .*QueryElevationComponent.*
[junit4] 2> 411001 T1687 oasc.SolrException.log Ignoring exception matching .*QueryElevationComponent.*
[junit4] 2> 411004 T1686 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 411005 T1686 oas.SolrTestCaseJ4.tearDown ###Ending testBadElevate
[junit4] 2> 411007 T1686 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 411007 T1686 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=573976317
[junit4] 2> 532128 T1686 oas.SolrTestCaseJ4.endTrackingSearchers ERROR ERROR: SolrIndexSearcher opens=1 closes=2
[junit4] 2> NOTE: test params are: codec=Lucene45, sim=DefaultSimilarity, locale=ar_TN, timezone=Europe/Malta
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_25 (64-bit)/cpus=16,threads=1,free=275523448,total=401604608
[junit4] 2> NOTE: All tests run in this JVM: [AddSchemaFieldsUpdateProcessorFactoryTest, DistributedTermsComponentTest, TestValueSourceCache, CircularListTest, EnumFieldTest, TestDynamicFieldCollectionResource, TestCopyFieldCollectionResource, TestSolrDeletionPolicy1, LukeRequestHandlerTest, OverseerCollectionProcessorTest, SpellPossibilityIteratorTest, SampleTest, PolyFieldTest, TestExtendedDismaxParser, TestBlendedInfixSuggestions, TestCollationFieldDocValues, SliceStateTest, SuggesterTest, TestManagedStopFilterFactory, TestSolrXml, QueryElevationComponentTest, TestBM25SimilarityFactory, MigrateRouteKeyTest, HdfsBasicDistributedZk2Test, RemoteQueryErrorTest, SearchHandlerTest, DocumentBuilderTest, TestPseudoReturnFields, StatelessScriptUpdateProcessorFactoryTest, DistributedSpellCheckComponentTest, LoggingHandlerTest, ClusterStateUpdateTest, TestWriterPerf, TestReplicationHandler, DateMathParserTest, BinaryUpdateRequestHandlerTest, TestJmxMonitoredMap, AutoCommitTest, BadComponentTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BadComponentTest -Dtests.seed=1AB0D9793B9437C9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar_TN -Dtests.timezone=Europe/Malta -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | BadComponentTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ERROR: SolrIndexSearcher opens=1 closes=2
[junit4] > at __randomizedtesting.SeedInfo.seed([1AB0D9793B9437C9]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:420)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:179)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] Completed on J0 in 122.80s, 1 test, 1 failure <<< FAILURES!
[...truncated 899 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:467: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:447: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/build.xml:45: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/extra-targets.xml:37: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/build.xml:189: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/solr/common-build.xml:490: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:1275: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-trunk-Java7/lucene/common-build.xml:907: There were test failures: 391 suites, 1645 tests, 1 suite-level error, 49 ignored (28 assumptions)
Total time: 75 minutes 18 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure