You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/12/07 17:53:24 UTC
[JENKINS] Lucene-Solr-4.x-Linux (64bit/ibm-j9-jdk7) - Build # 8507
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/8507/
Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
2 tests failed.
REGRESSION: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testMultiThreaded
Error Message:
expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
at __randomizedtesting.SeedInfo.seed([C7BAF05BCD9A2A2B:A62F2ABB254B20DD]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testMultiThreaded(SignatureUpdateProcessorFactoryTest.java:222)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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:358)
at java.lang.Thread.run(Thread.java:780)
REGRESSION: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection
Error Message:
expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
at __randomizedtesting.SeedInfo.seed([C7BAF05BCD9A2A2B:9C8A0B93CEDD8957]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection(SignatureUpdateProcessorFactoryTest.java:119)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:88)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55)
at java.lang.reflect.Method.invoke(Method.java:613)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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:358)
at java.lang.Thread.run(Thread.java:780)
Build Log:
[...truncated 10737 lines...]
[junit4] Suite: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest
[junit4] 2> 537630 T2714 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609
[junit4] 2> 537631 T2714 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 537632 T2714 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 537632 T2714 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 537666 T2714 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 537706 T2714 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 537707 T2714 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 537712 T2714 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 537988 T2714 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 537990 T2714 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 537992 T2714 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 537997 T2714 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 538001 T2714 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 538004 T2714 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 538004 T2714 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 538004 T2714 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 538011 T2714 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 538011 T2714 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr
[junit4] 2> 538012 T2714 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/'
[junit4] 2> 538046 T2714 oasc.CoreContainer.<init> New CoreContainer -271831130
[junit4] 2> 538046 T2714 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/]
[junit4] 2> 538047 T2714 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 538048 T2714 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 538048 T2714 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 538048 T2714 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 538048 T2714 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 538049 T2714 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 538049 T2714 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 538049 T2714 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 538050 T2714 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 538053 T2714 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 538053 T2714 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 538054 T2714 oasc.CoreContainer.load Host Name:
[junit4] 2> 538056 T2715 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1
[junit4] 2> 538057 T2715 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/'
[junit4] 2> 538057 T2715 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 538057 T2715 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 538089 T2715 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 538129 T2715 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 538130 T2715 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 538135 T2715 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 538413 T2715 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 538415 T2715 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 538416 T2715 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 538421 T2715 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 538425 T2715 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 538428 T2715 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 538428 T2715 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609/
[junit4] 2> 538428 T2715 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@424311c5
[junit4] 2> 538429 T2715 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609
[junit4] 2> 538429 T2715 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609/index/
[junit4] 2> 538429 T2715 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609/index' doesn't exist. Creating new index...
[junit4] 2> 538430 T2715 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609/index
[junit4] 2> 538430 T2715 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=62.1298828125, floorSegmentMB=1.4228515625, forceMergeDeletesPctAllowed=8.358441331396387, segmentsPerTier=46.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8869708969667677
[junit4] 2> 538431 T2715 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_1,generation=1}
[junit4] 2> 538431 T2715 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 538432 T2715 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 538433 T2715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 538434 T2715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 538434 T2715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 538434 T2715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 538434 T2715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 538435 T2715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 538435 T2715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 538435 T2715 oasup.UniqFieldsUpdateProcessorFactory.init WARN Use of the 'fields' init param in UniqFieldsUpdateProcessorFactory is deprecated, please use 'fieldName' (or another FieldMutatingUpdateProcessorFactory selector option) instead
[junit4] 2> 538436 T2715 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
[junit4] 2> 538436 T2715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 538436 T2715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 538437 T2715 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 538437 T2715 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 538437 T2715 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 538437 T2715 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 538438 T2715 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 538438 T2715 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 538438 T2715 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 538439 T2715 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 538439 T2715 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 538439 T2715 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 538440 T2715 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 538440 T2715 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 538440 T2715 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 538441 T2715 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 538441 T2715 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 538441 T2715 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 538442 T2715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 538442 T2715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 538442 T2715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 538443 T2715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 538443 T2715 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 538444 T2715 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 538444 T2715 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 538444 T2715 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 538445 T2715 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 538446 T2715 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 538447 T2715 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 538449 T2715 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 538449 T2715 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 538449 T2715 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=83.4853515625, floorSegmentMB=1.509765625, forceMergeDeletesPctAllowed=15.143873862612429, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6724909952002622
[junit4] 2> 538450 T2715 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_1,generation=1}
[junit4] 2> 538450 T2715 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 538450 T2715 oass.SolrIndexSearcher.<init> Opening Searcher@29d6946e main
[junit4] 2> 538451 T2715 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 538454 T2715 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 538472 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@29d6946e main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 538475 T2715 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 538477 T2714 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 538481 T2714 oas.SolrTestCaseJ4.setUp ###Starting testMultiThreaded
[junit4] 2> ASYNC NEW_CORE C1916 name=collection1 org.apache.solr.core.SolrCore@cebd4025
[junit4] 2> 538489 T2714 C1916 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 538490 T2714 C1916 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 538502 T2714 C1916 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538504 T2714 C1916 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_2,generation=2}
[junit4] 2> 538505 T2714 C1916 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 538506 T2714 C1916 oass.SolrIndexSearcher.<init> Opening Searcher@c4938442 main
[junit4] 2> 538507 T2714 C1916 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538508 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c4938442 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 538508 T2714 C1916 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 538524 T2725 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538531 T2725 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_3,generation=3}
[junit4] 2> 538531 T2725 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 538536 T2725 oass.SolrIndexSearcher.<init> Opening Searcher@45d8bcbd main
[junit4] 2> 538537 T2725 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538537 T2726 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538538 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@45d8bcbd main{StandardDirectoryReader(segments_3:10:nrt _1(4.7):C22/19:delGen=1)}
[junit4] 2> 538543 T2726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_4,generation=4}
[junit4] 2> 538543 T2726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 538548 T2726 oass.SolrIndexSearcher.<init> Opening Searcher@8e3a3c4e main
[junit4] 2> 538553 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8e3a3c4e main{StandardDirectoryReader(segments_4:18:nrt _3(4.7):C9/6:delGen=1)}
[junit4] 2> 538553 T2726 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538555 T2727 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538562 T2727 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_5,generation=5}
[junit4] 2> 538562 T2727 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 538567 T2727 oass.SolrIndexSearcher.<init> Opening Searcher@3d16c9d2 main
[junit4] 2> 538569 T2727 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538570 T2725 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538570 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3d16c9d2 main{StandardDirectoryReader(segments_5:26:nrt _5(4.7):C21/18:delGen=1)}
[junit4] 2> 538575 T2725 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_6,generation=6}
[junit4] 2> 538576 T2725 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 538582 T2725 oass.SolrIndexSearcher.<init> Opening Searcher@e58e771e main
[junit4] 2> 538584 T2725 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538586 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e58e771e main{StandardDirectoryReader(segments_6:33:nrt _6(4.7):C13/12:delGen=2 _7(4.7):C9/7:delGen=1)}
[junit4] 2> 538587 T2726 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538592 T2726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_7,generation=7}
[junit4] 2> 538593 T2726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 538597 T2726 oass.SolrIndexSearcher.<init> Opening Searcher@bf703f10 main
[junit4] 2> 538597 T2726 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538599 T2727 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538599 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@bf703f10 main{StandardDirectoryReader(segments_7:41:nrt _9(4.7):C13/10:delGen=1)}
[junit4] 2> 538605 T2727 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_8,generation=8}
[junit4] 2> 538605 T2727 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 538609 T2727 oass.SolrIndexSearcher.<init> Opening Searcher@e414f0c5 main
[junit4] 2> 538610 T2727 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538611 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e414f0c5 main{StandardDirectoryReader(segments_8:49:nrt _b(4.7):C10/6:delGen=1)}
[junit4] 2> 538612 T2725 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538617 T2725 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_9,generation=9}
[junit4] 2> 538617 T2725 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 538621 T2725 oass.SolrIndexSearcher.<init> Opening Searcher@6fd6569e main
[junit4] 2> 538625 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6fd6569e main{StandardDirectoryReader(segments_9:56:nrt _c(4.7):C13/12:delGen=2 _d(4.7):C11/8:delGen=1)}
[junit4] 2> 538625 T2725 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538625 T2726 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538629 T2726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_a,generation=10}
[junit4] 2> 538629 T2726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 538631 T2726 oass.SolrIndexSearcher.<init> Opening Searcher@17a8543f main
[junit4] 2> 538631 T2726 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538632 T2727 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538633 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@17a8543f main{StandardDirectoryReader(segments_a:62:nrt _e(4.7):C17/16:delGen=2 _f(4.7):C4/1:delGen=1)}
[junit4] 2> 538633 T2727 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_b,generation=11}
[junit4] 2> 538634 T2727 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
[junit4] 2> 538634 T2727 oass.SolrIndexSearcher.<init> Opening Searcher@1d44c6f2 main
[junit4] 2> 538635 T2727 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538635 T2725 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538636 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d44c6f2 main{StandardDirectoryReader(segments_b:64:nrt _f(4.7):C4/1:delGen=1)}
[junit4] 2> 538639 T2725 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_b,generation=11}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_c,generation=12}
[junit4] 2> 538639 T2725 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
[junit4] 2> 538641 T2725 oass.SolrIndexSearcher.<init> Opening Searcher@d2594aac main
[junit4] 2> 538641 T2725 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538641 T2726 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538642 T2726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_c,generation=12}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_d,generation=13}
[junit4] 2> 538642 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d2594aac main{StandardDirectoryReader(segments_c:71:nrt _f(4.7):C4/3:delGen=3 _h(4.7):C1)}
[junit4] 2> 538642 T2726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 13
[junit4] 2> 538643 T2726 oass.SolrIndexSearcher.<init> Opening Searcher@c74e9fa9 main
[junit4] 2> 538643 T2726 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538644 T2727 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538644 T2727 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 538645 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c74e9fa9 main{StandardDirectoryReader(segments_c:71:nrt _f(4.7):C4/3:delGen=3 _h(4.7):C1)}
[junit4] 2> 538647 T2727 oass.SolrIndexSearcher.<init> Opening Searcher@7a53a2f2 main
[junit4] 2> 538647 T2727 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538648 T2725 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538649 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7a53a2f2 main{StandardDirectoryReader(segments_d:75:nrt _h(4.7):C1 _i(4.7):C1)}
[junit4] 2> 538650 T2725 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_d,generation=13}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_e,generation=14}
[junit4] 2> 538650 T2725 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 14
[junit4] 2> 538652 T2725 oass.SolrIndexSearcher.<init> Opening Searcher@38526cbf main
[junit4] 2> 538652 T2725 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538653 T2726 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538653 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@38526cbf main{StandardDirectoryReader(segments_e:83:nrt _h(4.7):C1 _k(4.7):C1)}
[junit4] 2> 538653 T2726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_e,generation=14}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_f,generation=15}
[junit4] 2> 538654 T2726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 15
[junit4] 2> 538654 T2726 oass.SolrIndexSearcher.<init> Opening Searcher@364384fa main
[junit4] 2> 538655 T2726 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538655 T2727 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538655 T2727 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 538656 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@364384fa main{StandardDirectoryReader(segments_e:83:nrt _h(4.7):C1 _k(4.7):C1)}
[junit4] 2> 538664 T2727 oass.SolrIndexSearcher.<init> Opening Searcher@eee1c447 main
[junit4] 2> 538664 T2727 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538665 T2725 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538666 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@eee1c447 main{StandardDirectoryReader(segments_f:87:nrt _h(4.7):C1 _l(4.7):C1)}
[junit4] 2> 538667 T2725 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_f,generation=15}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_g,generation=16}
[junit4] 2> 538668 T2725 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 16
[junit4] 2> 538669 T2725 oass.SolrIndexSearcher.<init> Opening Searcher@c61eb257 main
[junit4] 2> 538669 T2725 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538670 T2726 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538671 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c61eb257 main{StandardDirectoryReader(segments_g:93:nrt _m(4.7):C1 _n(4.7):C1)}
[junit4] 2> 538671 T2726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_g,generation=16}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_h,generation=17}
[junit4] 2> 538671 T2726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 17
[junit4] 2> 538672 T2726 oass.SolrIndexSearcher.<init> Opening Searcher@dc93aa69 main
[junit4] 2> 538672 T2726 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538672 T2727 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538673 T2727 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 538674 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@dc93aa69 main{StandardDirectoryReader(segments_h:95:nrt _n(4.7):C1)}
[junit4] 2> 538675 T2727 oass.SolrIndexSearcher.<init> Opening Searcher@40f48a5b main
[junit4] 2> 538676 T2727 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538676 T2725 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538677 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@40f48a5b main{StandardDirectoryReader(segments_h:97:nrt _n(4.7):C1 _o(4.7):C1)}
[junit4] 2> 538678 T2725 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_h,generation=17}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_i,generation=18}
[junit4] 2> 538679 T2725 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 18
[junit4] 2> 538680 T2725 oass.SolrIndexSearcher.<init> Opening Searcher@e0f9ebb1 main
[junit4] 2> 538680 T2725 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538681 T2726 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538682 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e0f9ebb1 main{StandardDirectoryReader(segments_i:103:nrt _n(4.7):C1 _p(4.7):C1 _q(4.7):C1)}
[junit4] 2> 538682 T2726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_i,generation=18}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_j,generation=19}
[junit4] 2> 538682 T2726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 19
[junit4] 2> 538683 T2726 oass.SolrIndexSearcher.<init> Opening Searcher@71a813cb main
[junit4] 2> 538683 T2726 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538684 T2727 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538684 T2727 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 538685 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@71a813cb main{StandardDirectoryReader(segments_j:105:nrt _n(4.7):C1 _q(4.7):C1)}
[junit4] 2> 538686 T2727 oass.SolrIndexSearcher.<init> Opening Searcher@641063a3 main
[junit4] 2> 538687 T2727 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538688 T2725 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538689 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@641063a3 main{StandardDirectoryReader(segments_j:107:nrt _n(4.7):C1 _q(4.7):C1 _r(4.7):C1)}
[junit4] 2> 538689 T2725 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_j,generation=19}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_k,generation=20}
[junit4] 2> 538690 T2725 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 20
[junit4] 2> 538691 T2725 oass.SolrIndexSearcher.<init> Opening Searcher@86d517d5 main
[junit4] 2> 538692 T2725 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538692 T2726 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538693 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@86d517d5 main{StandardDirectoryReader(segments_k:115:nrt _s(4.7):C1 _t(4.7):C1)}
[junit4] 2> 538693 T2726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_k,generation=20}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_l,generation=21}
[junit4] 2> 538693 T2726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 21
[junit4] 2> 538694 T2726 oass.SolrIndexSearcher.<init> Opening Searcher@24b512af main
[junit4] 2> 538694 T2726 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538695 T2727 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538696 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@24b512af main{StandardDirectoryReader(segments_l:117:nrt _t(4.7):C1)}
[junit4] 2> 538696 T2727 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_l,generation=21}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_m,generation=22}
[junit4] 2> 538697 T2727 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 22
[junit4] 2> 538698 T2727 oass.SolrIndexSearcher.<init> Opening Searcher@6ff7b1b main
[junit4] 2> 538698 T2727 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538699 T2725 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538699 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6ff7b1b main{StandardDirectoryReader(segments_m:119:nrt _t(4.7):C1 _u(4.7):C1)}
[junit4] 2> 538700 T2725 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_m,generation=22}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_n,generation=23}
[junit4] 2> 538701 T2725 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 23
[junit4] 2> 538701 T2725 oass.SolrIndexSearcher.<init> Opening Searcher@285ede87 main
[junit4] 2> 538702 T2725 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538702 T2726 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538703 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@285ede87 main{StandardDirectoryReader(segments_n:123:nrt _t(4.7):C1 _v(4.7):C1)}
[junit4] 2> 538703 T2726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_n,generation=23}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_o,generation=24}
[junit4] 2> 538704 T2726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 24
[junit4] 2> 538704 T2726 oass.SolrIndexSearcher.<init> Opening Searcher@1214b7cb main
[junit4] 2> 538705 T2726 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538705 T2727 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538706 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1214b7cb main{StandardDirectoryReader(segments_o:127:nrt _w(4.7):C1)}
[junit4] 2> 538707 T2727 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_o,generation=24}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_p,generation=25}
[junit4] 2> 538707 T2727 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 25
[junit4] 2> 538708 T2727 oass.SolrIndexSearcher.<init> Opening Searcher@f3ed23d5 main
[junit4] 2> 538708 T2727 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538709 T2725 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538710 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f3ed23d5 main{StandardDirectoryReader(segments_p:129:nrt _w(4.7):C1 _x(4.7):C1)}
[junit4] 2> 538710 T2725 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_p,generation=25}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_q,generation=26}
[junit4] 2> 538711 T2725 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 26
[junit4] 2> 538712 T2725 oass.SolrIndexSearcher.<init> Opening Searcher@c7ae1017 main
[junit4] 2> 538712 T2725 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538713 T2726 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538713 T2726 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_q,generation=26}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_r,generation=27}
[junit4] 2> 538714 T2726 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 27
[junit4] 2> 538713 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c7ae1017 main{StandardDirectoryReader(segments_q:137:nrt _w(4.7):C1 _z(4.7):C1)}
[junit4] 2> 538714 T2726 oass.SolrIndexSearcher.<init> Opening Searcher@b53cfd2a main
[junit4] 2> 538715 T2726 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538715 T2727 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538716 T2727 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 538716 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b53cfd2a main{StandardDirectoryReader(segments_q:137:nrt _w(4.7):C1 _z(4.7):C1)}
[junit4] 2> 538717 T2727 oass.SolrIndexSearcher.<init> Opening Searcher@96671c4d main
[junit4] 2> 538717 T2727 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538718 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@96671c4d main{StandardDirectoryReader(segments_q:137:nrt _w(4.7):C1 _z(4.7):C1)}
[junit4] 2> ASYNC NEW_CORE C1917 name=collection1 org.apache.solr.core.SolrCore@cebd4025
[junit4] 2> 538723 T2714 C1917 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538724 T2714 C1917 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 538724 T2714 C1917 oass.SolrIndexSearcher.<init> Opening Searcher@e15d48bb main
[junit4] 2> 538725 T2714 C1917 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538726 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e15d48bb main{StandardDirectoryReader(segments_q:137:nrt _w(4.7):C1 _z(4.7):C1)}
[junit4] 2> 538727 T2714 C1917 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 4
[junit4] 2> 538728 T2714 oas.SolrTestCaseJ4.tearDown ###Ending testMultiThreaded
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SignatureUpdateProcessorFactoryTest -Dtests.method=testMultiThreaded -Dtests.seed=C7BAF05BCD9A2A2B -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=zh_CN -Dtests.timezone=Europe/Minsk -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.26s J0 | SignatureUpdateProcessorFactoryTest.testMultiThreaded <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([C7BAF05BCD9A2A2B:A62F2ABB254B20DD]:0)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testMultiThreaded(SignatureUpdateProcessorFactoryTest.java:222)
[junit4] > at java.lang.Thread.run(Thread.java:780)
[junit4] 2> 538736 T2714 oas.SolrTestCaseJ4.setUp ###Starting testDupeAllFieldsDetection
[junit4] 2> 538742 T2714 C1917 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 538743 T2714 C1917 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 538749 T2714 C1917 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538750 T2714 C1917 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_r,generation=27}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_s,generation=28}
[junit4] 2> 538750 T2714 C1917 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 28
[junit4] 2> 538751 T2714 C1917 oass.SolrIndexSearcher.<init> Opening Searcher@589cb2e main
[junit4] 2> 538752 T2714 C1917 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538753 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@589cb2e main{StandardDirectoryReader(segments_s:138:nrt)}
[junit4] 2> 538754 T2714 C1917 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 538759 T2714 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538761 T2714 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_s,generation=28}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_t,generation=29}
[junit4] 2> 538761 T2714 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 29
[junit4] 2> 538762 T2714 oass.SolrIndexSearcher.<init> Opening Searcher@a07385af main
[junit4] 2> 538762 T2714 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538764 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a07385af main{StandardDirectoryReader(segments_t:140:nrt _10(4.7):C3)}
[junit4] 2> 538764 T2714 oas.SolrTestCaseJ4.tearDown ###Ending testDupeAllFieldsDetection
[junit4] 2> 538768 T2714 oas.SolrTestCaseJ4.setUp ###Starting testDupeDetection
[junit4] 2> 538773 T2714 C1917 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 538774 T2714 C1917 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 538779 T2714 C1917 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538780 T2714 C1917 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_t,generation=29}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_u,generation=30}
[junit4] 2> 538780 T2714 C1917 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 30
[junit4] 2> 538781 T2714 C1917 oass.SolrIndexSearcher.<init> Opening Searcher@283c4db main
[junit4] 2> 538782 T2714 C1917 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538783 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@283c4db main{StandardDirectoryReader(segments_u:141:nrt)}
[junit4] 2> 538784 T2714 C1917 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 538788 T2714 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538790 T2714 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_u,generation=30}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_v,generation=31}
[junit4] 2> 538790 T2714 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 31
[junit4] 2> 538791 T2714 oass.SolrIndexSearcher.<init> Opening Searcher@a80888fe main
[junit4] 2> 538791 T2714 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538793 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a80888fe main{StandardDirectoryReader(segments_v:143:nrt _11(4.7):C2)}
[junit4] 2> 538795 T2714 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538797 T2714 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_v,generation=31}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_w,generation=32}
[junit4] 2> 538797 T2714 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 32
[junit4] 2> 538798 T2714 oass.SolrIndexSearcher.<init> Opening Searcher@485144b9 main
[junit4] 2> 538799 T2714 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538800 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@485144b9 main{StandardDirectoryReader(segments_w:146:nrt _11(4.7):C2/1:delGen=1 _12(4.7):C1)}
[junit4] 2> 538800 T2714 oas.SolrTestCaseJ4.tearDown ###Ending testDupeDetection
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SignatureUpdateProcessorFactoryTest -Dtests.method=testDupeDetection -Dtests.seed=C7BAF05BCD9A2A2B -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=zh_CN -Dtests.timezone=Europe/Minsk -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.04s J0 | SignatureUpdateProcessorFactoryTest.testDupeDetection <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([C7BAF05BCD9A2A2B:9C8A0B93CEDD8957]:0)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.checkNumDocs(SignatureUpdateProcessorFactoryTest.java:71)
[junit4] > at org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest.testDupeDetection(SignatureUpdateProcessorFactoryTest.java:119)
[junit4] > at java.lang.Thread.run(Thread.java:780)
[junit4] 2> 538806 T2714 oas.SolrTestCaseJ4.setUp ###Starting testNonIndexedSignatureField
[junit4] 2> 538811 T2714 C1917 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 538812 T2714 C1917 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 538817 T2714 C1917 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538818 T2714 C1917 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_w,generation=32}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_x,generation=33}
[junit4] 2> 538819 T2714 C1917 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 33
[junit4] 2> 538820 T2714 C1917 oass.SolrIndexSearcher.<init> Opening Searcher@ede1cd0b main
[junit4] 2> 538820 T2714 C1917 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538822 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ede1cd0b main{StandardDirectoryReader(segments_x:147:nrt)}
[junit4] 2> 538822 T2714 C1917 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 538826 T2714 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538827 T2714 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_x,generation=33}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_y,generation=34}
[junit4] 2> 538828 T2714 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 34
[junit4] 2> 538829 T2714 oass.SolrIndexSearcher.<init> Opening Searcher@d5f37912 main
[junit4] 2> 538829 T2714 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538831 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d5f37912 main{StandardDirectoryReader(segments_y:149:nrt _13(4.7):C2)}
[junit4] 2> 538831 T2714 oas.SolrTestCaseJ4.tearDown ###Ending testNonIndexedSignatureField
[junit4] 2> 538835 T2714 oas.SolrTestCaseJ4.setUp ###Starting testFailNonIndexedSigWithOverwriteDupes
[junit4] 2> 538840 T2714 C1917 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 538841 T2714 C1917 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 538846 T2714 C1917 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538847 T2714 C1917 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_y,generation=34}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_z,generation=35}
[junit4] 2> 538847 T2714 C1917 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 35
[junit4] 2> 538848 T2714 C1917 oass.SolrIndexSearcher.<init> Opening Searcher@6114ea47 main
[junit4] 2> 538849 T2714 C1917 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538850 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6114ea47 main{StandardDirectoryReader(segments_z:150:nrt)}
[junit4] 2> 538851 T2714 C1917 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 538852 T2714 oas.SolrTestCaseJ4.tearDown ###Ending testFailNonIndexedSigWithOverwriteDupes
[junit4] 2> 538856 T2714 oas.SolrTestCaseJ4.setUp ###Starting testNonStringFieldsValues
[junit4] 2> 538861 T2714 C1917 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 538861 T2714 C1917 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 0
[junit4] 2> 538867 T2714 C1917 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538868 T2714 C1917 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_z,generation=35}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_10,generation=36}
[junit4] 2> 538868 T2714 C1917 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 36
[junit4] 2> 538869 T2714 C1917 oass.SolrIndexSearcher.<init> Opening Searcher@ecf72ce4 main
[junit4] 2> 538870 T2714 C1917 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538871 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ecf72ce4 main{StandardDirectoryReader(segments_10:151:nrt)}
[junit4] 2> 538872 T2714 C1917 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 538874 T2714 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 538876 T2714 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 538878 T2714 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_10,generation=36}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@958d4055 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75bdc57c)),segFN=segments_11,generation=37}
[junit4] 2> 538878 T2714 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 37
[junit4] 2> 538879 T2714 oass.SolrIndexSearcher.<init> Opening Searcher@cdfcc5 main
[junit4] 2> 538879 T2714 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 538881 T2716 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@cdfcc5 main{StandardDirectoryReader(segments_11:153:nrt _14(4.7):C5/1:delGen=1)}
[junit4] 2> 538881 T2714 oas.SolrTestCaseJ4.tearDown ###Ending testNonStringFieldsValues
[junit4] 2> 538883 T2714 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 538883 T2714 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=-271831130
[junit4] 2> 538884 T2714 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@cebd4025
[junit4] 2> 538893 T2714 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=42,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=253,cumulative_deletesById=0,cumulative_deletesByQuery=6,cumulative_errors=0}
[junit4] 2> 538893 T2714 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 538893 T2714 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 538894 T2714 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 538894 T2714 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 538894 T2714 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 538895 T2714 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609/index;done=false>>]
[junit4] 2> 538895 T2714 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609/index
[junit4] 2> 538895 T2714 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609;done=false>>]
[junit4] 2> 538896 T2714 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1386435075609
[junit4] 2> NOTE: test params are: codec=Appending, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=zh_CN, timezone=Europe/Minsk
[junit4] 2> NOTE: Linux 3.8.0-34-generic amd64/IBM Corporation 1.7.0 (64-bit)/cpus=8,threads=1,free=52781504,total=184418304
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrQueryParser, TestLFUCache, FastVectorHighlighterTest, DeleteInactiveReplicaTest, FieldMutatingUpdateProcessorTest, TestManagedSchemaFieldResource, DistributedQueryElevationComponentTest, LegacyHTMLStripCharFilterTest, TestJoin, SpatialFilterTest, TestShardHandlerFactory, QueryParsingTest, BadComponentTest, TestImplicitCoreProperties, TestSolrDeletionPolicy1, PolyFieldTest, TestFieldResource, DOMUtilTest, TestExtendedDismaxParser, OverseerCollectionProcessorTest, AddBlockUpdateTest, TestSolrXMLSerializer, ParsingFieldUpdateProcessorsTest, UpdateRequestProcessorFactoryTest, TestElisionMultitermQuery, SimpleFacetsTest, TermVectorComponentTest, RegexBoostProcessorTest, LoggingHandlerTest, SchemaVersionSpecificBehaviorTest, SystemInfoHandlerTest, TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest, TestPHPSerializedResponseWriter, DistributedTermsComponentTest, UpdateParamsTest, TestSchemaVersionResource, TestFunctionQuery, BinaryUpdateRequestHandlerTest, TestNRTOpen, TestGroupingSearch, LeaderElectionTest, QueryElevationComponentTest, TestWriterPerf, MoreLikeThisHandlerTest, TestBM25SimilarityFactory, QueryResultKeyTest, TestJmxMonitoredMap, SyncSliceTest, CSVRequestHandlerTest, HdfsRecoveryZkTest, ShardRoutingTest, TestRealTimeGet, SolrIndexSplitterTest, ResourceLoaderTest, TestFileDictionaryLookup, TestSort, HdfsBasicDistributedZkTest, PeerSyncTest, TestSuggestSpellingConverter, TestUniqueKeyFieldResource, OverseerTest, SpellPossibilityIteratorTest, HdfsCollectionsAPIDistributedZkTest, TestStressUserVersions, TestReloadAndDeleteDocs, TestDistributedMissingSort, HdfsLockFactoryTest, TestSerializedLuceneMatchVersion, BJQParserTest, TestReplicationHandler, TestNonNRTOpen, ShardSplitTest, DateMathParserTest, SOLR749Test, TestStressVersions, BasicDistributedZkTest, ReturnFieldsTest, TestCollationFieldDocValues, SuggesterTest, DocumentBuilderTest, TestSchemaResource, TestPostingsSolrHighlighter, URLClassifyProcessorTest, DocValuesTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, ZkSolrClientTest, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, SliceStateUpdateTest, AutoCommitTest, TestFaceting, TestMultiCoreConfBootstrap, HardAutoCommitTest, TestRangeQuery, TestSolr4Spatial, SolrCmdDistributorTest, ConvertedLegacyTest, TestLazyCores, SoftAutoCommitTest, SignatureUpdateProcessorFactoryTest]
[junit4] Completed on J0 in 1.30s, 6 tests, 2 failures <<< FAILURES!
[...truncated 430 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:426: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:419: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:491: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1307: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:940: There were test failures: 347 suites, 1516 tests, 2 failures, 67 ignored (6 assumptions)
Total time: 37 minutes 30 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/ibm-j9-jdk7 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.7.0_60-ea-b01) - Build
# 8508 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/8508/
Java: 64bit/jdk1.7.0_60-ea-b01 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([BAA5374BB3E2F423]:0)
FAILED: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest.testDistribSearch
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([BAA5374BB3E2F423]:0)
Build Log:
[...truncated 11077 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest
[junit4] 2> 427396 T1713 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 427400 T1713 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./solrtest-ChaosMonkeyNothingIsSafeTest-1386440156527
[junit4] 2> 427401 T1713 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 427401 T1714 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 427501 T1713 oasc.ZkTestServer.run start zk server on port:60655
[junit4] 2> 427502 T1713 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 427506 T1720 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@351ea94c name:ZooKeeperConnection Watcher:127.0.0.1:60655 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 427507 T1713 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 427507 T1713 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 427513 T1713 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 427514 T1722 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@596bf1bc name:ZooKeeperConnection Watcher:127.0.0.1:60655/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 427515 T1713 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 427515 T1713 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 427517 T1713 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 427518 T1713 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 427521 T1713 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 427522 T1713 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 427523 T1713 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 427526 T1713 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 427526 T1713 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 427529 T1713 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 427529 T1713 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 427531 T1713 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 427532 T1713 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 427534 T1713 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 427535 T1713 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 427537 T1713 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 427537 T1713 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 427539 T1713 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 427540 T1713 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 427542 T1713 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 427543 T1713 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 427545 T1713 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 427545 T1713 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 427547 T1713 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 427548 T1713 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 427550 T1713 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 427550 T1713 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 427637 T1713 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 427640 T1713 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:46733
[junit4] 2> 427640 T1713 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 427640 T1713 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 427641 T1713 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1386440156678
[junit4] 2> 427641 T1713 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1386440156678/'
[junit4] 2> 427651 T1713 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1386440156678/solr.xml
[junit4] 2> 427695 T1713 oasc.CoreContainer.<init> New CoreContainer 872305869
[junit4] 2> 427696 T1713 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1386440156678/]
[junit4] 2> 427697 T1713 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 427697 T1713 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 427698 T1713 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 427698 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 427698 T1713 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 427699 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 427699 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 427699 T1713 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 427700 T1713 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 427705 T1713 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 427706 T1713 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 427706 T1713 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 427707 T1713 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60655/solr
[junit4] 2> 427707 T1713 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 427708 T1713 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 427710 T1733 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b5ea0e name:ZooKeeperConnection Watcher:127.0.0.1:60655 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 427710 T1713 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 427713 T1713 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 427715 T1735 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@201a6e9 name:ZooKeeperConnection Watcher:127.0.0.1:60655/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 427715 T1713 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 427716 T1713 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 427718 T1713 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 427720 T1713 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 427721 T1713 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46733_
[junit4] 2> 427722 T1713 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:46733_
[junit4] 2> 427723 T1713 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 427726 T1713 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 427727 T1713 oasc.Overseer.start Overseer (id=90861742098284547-127.0.0.1:46733_-n_0000000000) starting
[junit4] 2> 427730 T1713 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 427733 T1713 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 427733 T1737 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 427736 T1713 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 427737 T1713 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 427739 T1736 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 427741 T1738 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 427741 T1738 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 427743 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 427743 T1738 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 427744 T1736 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 427745 T1736 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:46733",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:46733_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":null}
[junit4] 2> 427745 T1736 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 427745 T1736 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 427747 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 427748 T1735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 428744 T1738 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 428744 T1738 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1386440156678/collection1
[junit4] 2> 428744 T1738 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 428745 T1738 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 428745 T1738 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 428746 T1738 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1386440156678/collection1/'
[junit4] 2> 428746 T1738 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1386440156678/collection1/lib/classes/' to classloader
[junit4] 2> 428747 T1738 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1386440156678/collection1/lib/README' to classloader
[junit4] 2> 428782 T1738 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 428811 T1738 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 428812 T1738 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 428821 T1738 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 429026 T1738 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 429028 T1738 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 429029 T1738 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 429033 T1738 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 429050 T1738 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 429050 T1738 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-controljetty-1386440156678/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/control/data/
[junit4] 2> 429051 T1738 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3877a90f
[junit4] 2> 429051 T1738 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/control/data
[junit4] 2> 429051 T1738 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/control/data/index/
[junit4] 2> 429052 T1738 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 429052 T1738 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/control/data/index
[junit4] 2> 429052 T1738 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=8.7353515625, floorSegmentMB=0.3603515625, forceMergeDeletesPctAllowed=9.585725146992013, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2914254823518492
[junit4] 2> 429054 T1738 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 429054 T1738 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 429056 T1738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 429057 T1738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 429057 T1738 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 429057 T1738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 429057 T1738 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 429057 T1738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 429058 T1738 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 429058 T1738 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 429058 T1738 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 429059 T1738 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 429059 T1738 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 429059 T1738 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 429066 T1738 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 429067 T1738 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 429067 T1738 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 429067 T1738 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 429069 T1738 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 429071 T1738 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 429071 T1738 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 429072 T1738 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=14, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 429073 T1738 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 429073 T1738 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 429073 T1738 oass.SolrIndexSearcher.<init> Opening Searcher@57f9dbeb main
[junit4] 2> 429075 T1739 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@57f9dbeb main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 429075 T1738 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 429076 T1738 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:46733 collection:control_collection shard:shard1
[junit4] 2> 429076 T1738 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 429081 T1738 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 429082 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 429082 T1738 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 429083 T1738 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1648 name=collection1 org.apache.solr.core.SolrCore@53894eb4 url=http://127.0.0.1:46733/collection1 node=127.0.0.1:46733_ C1648_STATE=coll:control_collection core:collection1 props:{state=down, base_url=http://127.0.0.1:46733, core=collection1, node_name=127.0.0.1:46733_}
[junit4] 2> 429083 T1738 C1648 P46733 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:46733/collection1/
[junit4] 2> 429083 T1738 C1648 P46733 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 429083 T1736 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 429083 T1738 C1648 P46733 oasc.SyncStrategy.syncToMe http://127.0.0.1:46733/collection1/ has no replicas
[junit4] 2> 429084 T1738 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:46733/collection1/ shard1
[junit4] 2> 429084 T1738 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 429086 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 429088 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 429091 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 429194 T1735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 429239 T1738 oasc.ZkController.register We are http://127.0.0.1:46733/collection1/ and leader is http://127.0.0.1:46733/collection1/
[junit4] 2> 429239 T1738 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:46733
[junit4] 2> 429240 T1738 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 429240 T1738 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 429240 T1738 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 429243 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 429243 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 429243 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 429245 T1738 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 429246 T1713 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 429247 T1713 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 429250 T1736 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 429250 T1713 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 429251 T1736 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:46733",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:46733_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 429252 T1742 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@77ed9570 name:ZooKeeperConnection Watcher:127.0.0.1:60655/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 429252 T1713 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 429255 T1713 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 429256 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 429257 T1713 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 429329 T1713 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 429331 T1713 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59216
[junit4] 2> 429332 T1713 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 429332 T1713 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 429332 T1713 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1386440158383
[junit4] 2> 429333 T1713 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1386440158383/'
[junit4] 2> 429343 T1713 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1386440158383/solr.xml
[junit4] 2> 429358 T1735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 429358 T1742 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 429365 T1713 oasc.CoreContainer.<init> New CoreContainer 315285622
[junit4] 2> 429366 T1713 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1386440158383/]
[junit4] 2> 429366 T1713 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 429367 T1713 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 429367 T1713 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 429367 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 429367 T1713 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 429368 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 429368 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 429368 T1713 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 429368 T1713 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 429372 T1713 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 429372 T1713 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 429372 T1713 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 429373 T1713 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60655/solr
[junit4] 2> 429373 T1713 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 429374 T1713 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 429375 T1753 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a1369c5 name:ZooKeeperConnection Watcher:127.0.0.1:60655 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 429376 T1713 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 429380 T1713 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 429381 T1755 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@76efed40 name:ZooKeeperConnection Watcher:127.0.0.1:60655/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 429381 T1713 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 429384 T1713 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 430387 T1713 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59216_
[junit4] 2> 430388 T1713 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59216_
[junit4] 2> 430391 T1742 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 430391 T1755 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 430391 T1735 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 430396 T1756 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 430396 T1756 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 430397 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 430397 T1756 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 430397 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 430397 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 430398 T1736 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 430399 T1736 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59216",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:59216_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 430399 T1736 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 430399 T1736 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 430403 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 430505 T1742 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> 430505 T1735 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> 430505 T1755 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> 431397 T1756 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 431398 T1756 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1386440158383/collection1
[junit4] 2> 431398 T1756 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 431400 T1756 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 431400 T1756 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 431402 T1756 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1386440158383/collection1/'
[junit4] 2> 431404 T1756 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1386440158383/collection1/lib/classes/' to classloader
[junit4] 2> 431404 T1756 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1386440158383/collection1/lib/README' to classloader
[junit4] 2> 431456 T1756 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 431499 T1756 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 431501 T1756 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 431507 T1756 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 431757 T1756 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 431759 T1756 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 431760 T1756 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 431763 T1756 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 431782 T1756 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 431782 T1756 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty1-1386440158383/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty1/
[junit4] 2> 431783 T1756 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3877a90f
[junit4] 2> 431783 T1756 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty1
[junit4] 2> 431783 T1756 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty1/index/
[junit4] 2> 431784 T1756 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 431784 T1756 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty1/index
[junit4] 2> 431785 T1756 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=8.7353515625, floorSegmentMB=0.3603515625, forceMergeDeletesPctAllowed=9.585725146992013, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2914254823518492
[junit4] 2> 431787 T1756 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 431788 T1756 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 431791 T1756 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 431791 T1756 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 431791 T1756 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 431792 T1756 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 431792 T1756 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 431792 T1756 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 431792 T1756 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 431793 T1756 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 431793 T1756 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 431793 T1756 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 431793 T1756 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 431794 T1756 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 431794 T1756 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 431794 T1756 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 431794 T1756 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 431795 T1756 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 431797 T1756 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 431799 T1756 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 431799 T1756 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 431799 T1756 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=14, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 431800 T1756 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 431801 T1756 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 431801 T1756 oass.SolrIndexSearcher.<init> Opening Searcher@535b6bd main
[junit4] 2> 431803 T1757 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@535b6bd main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 431805 T1756 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 431805 T1756 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:59216 collection:collection1 shard:shard2
[junit4] 2> 431806 T1756 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 431810 T1756 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 431812 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 431812 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 431812 T1756 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 431812 T1756 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1649 name=collection1 org.apache.solr.core.SolrCore@195c7e59 url=http://127.0.0.1:59216/collection1 node=127.0.0.1:59216_ C1649_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:59216, core=collection1, node_name=127.0.0.1:59216_}
[junit4] 2> 431813 T1756 C1649 P59216 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:59216/collection1/
[junit4] 2> 431813 T1756 C1649 P59216 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 431812 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 431813 T1736 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 431813 T1756 C1649 P59216 oasc.SyncStrategy.syncToMe http://127.0.0.1:59216/collection1/ has no replicas
[junit4] 2> 431814 T1756 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:59216/collection1/ shard2
[junit4] 2> 431814 T1756 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 431816 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 431820 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 431823 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 431925 T1735 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> 431925 T1742 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> 431925 T1755 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> 431971 T1756 oasc.ZkController.register We are http://127.0.0.1:59216/collection1/ and leader is http://127.0.0.1:59216/collection1/
[junit4] 2> 431971 T1756 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:59216
[junit4] 2> 431971 T1756 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 431971 T1756 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 431972 T1756 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 431973 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 431974 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 431974 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 431974 T1756 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 431975 T1736 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 431975 T1713 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 431976 T1713 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 431976 T1736 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:59216",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:59216_",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 431978 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 432052 T1713 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 432054 T1713 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:45400
[junit4] 2> 432055 T1713 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 432055 T1713 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 432055 T1713 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1386440161103
[junit4] 2> 432056 T1713 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1386440161103/'
[junit4] 2> 432065 T1713 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1386440161103/solr.xml
[junit4] 2> 432080 T1735 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> 432080 T1755 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> 432080 T1742 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> 432086 T1713 oasc.CoreContainer.<init> New CoreContainer 542761513
[junit4] 2> 432087 T1713 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1386440161103/]
[junit4] 2> 432087 T1713 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 432088 T1713 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 432088 T1713 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 432088 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 432088 T1713 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 432088 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 432089 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 432089 T1713 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 432089 T1713 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 432093 T1713 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 432093 T1713 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 432093 T1713 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 432093 T1713 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60655/solr
[junit4] 2> 432094 T1713 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 432095 T1713 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 432096 T1769 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@20daff03 name:ZooKeeperConnection Watcher:127.0.0.1:60655 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 432097 T1713 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 432100 T1713 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 432101 T1771 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@19002f20 name:ZooKeeperConnection Watcher:127.0.0.1:60655/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 432101 T1713 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 432104 T1713 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 433107 T1713 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45400_
[junit4] 2> 433108 T1713 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:45400_
[junit4] 2> 433112 T1755 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 433112 T1735 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 433113 T1771 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 433113 T1742 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 433120 T1772 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 433120 T1772 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 433122 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 433122 T1772 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 433123 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 433124 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 433126 T1736 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 433127 T1736 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:45400",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:45400_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 433127 T1736 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 433127 T1736 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 433131 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 433235 T1735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 433235 T1771 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 433235 T1755 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 433235 T1742 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 434123 T1772 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 434124 T1772 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1386440161103/collection1
[junit4] 2> 434124 T1772 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 434125 T1772 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 434126 T1772 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 434127 T1772 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1386440161103/collection1/'
[junit4] 2> 434128 T1772 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1386440161103/collection1/lib/classes/' to classloader
[junit4] 2> 434129 T1772 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1386440161103/collection1/lib/README' to classloader
[junit4] 2> 434185 T1772 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 434205 T1772 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 434206 T1772 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 434213 T1772 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 434449 T1772 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 434451 T1772 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 434452 T1772 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 434454 T1772 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 434469 T1772 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 434469 T1772 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty2-1386440161103/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty2/
[junit4] 2> 434470 T1772 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3877a90f
[junit4] 2> 434470 T1772 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty2
[junit4] 2> 434470 T1772 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty2/index/
[junit4] 2> 434471 T1772 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 434471 T1772 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty2/index
[junit4] 2> 434471 T1772 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=8.7353515625, floorSegmentMB=0.3603515625, forceMergeDeletesPctAllowed=9.585725146992013, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2914254823518492
[junit4] 2> 434473 T1772 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 434474 T1772 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 434476 T1772 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 434477 T1772 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 434477 T1772 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 434477 T1772 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 434477 T1772 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 434477 T1772 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 434478 T1772 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 434478 T1772 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 434478 T1772 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 434479 T1772 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 434479 T1772 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 434479 T1772 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 434479 T1772 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 434480 T1772 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 434480 T1772 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 434481 T1772 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 434483 T1772 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 434484 T1772 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 434485 T1772 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 434485 T1772 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=14, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 434486 T1772 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 434486 T1772 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 434486 T1772 oass.SolrIndexSearcher.<init> Opening Searcher@dee5938 main
[junit4] 2> 434489 T1773 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@dee5938 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 434490 T1772 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 434491 T1772 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:45400 collection:collection1 shard:shard1
[junit4] 2> 434491 T1772 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 434497 T1772 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 434499 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 434499 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 434499 T1772 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 434499 T1772 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 434499 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> ASYNC NEW_CORE C1650 name=collection1 org.apache.solr.core.SolrCore@3bd0f352 url=http://127.0.0.1:45400/collection1 node=127.0.0.1:45400_ C1650_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:45400, core=collection1, node_name=127.0.0.1:45400_}
[junit4] 2> 434499 T1772 C1650 P45400 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:45400/collection1/
[junit4] 2> 434500 T1772 C1650 P45400 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 434500 T1772 C1650 P45400 oasc.SyncStrategy.syncToMe http://127.0.0.1:45400/collection1/ has no replicas
[junit4] 2> 434500 T1772 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:45400/collection1/ shard1
[junit4] 2> 434500 T1772 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 434500 T1736 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 434502 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 434503 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 434505 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 434607 T1755 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 434607 T1742 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 434607 T1735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 434607 T1771 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 434654 T1772 oasc.ZkController.register We are http://127.0.0.1:45400/collection1/ and leader is http://127.0.0.1:45400/collection1/
[junit4] 2> 434654 T1772 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:45400
[junit4] 2> 434654 T1772 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 434654 T1772 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 434655 T1772 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 434656 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 434656 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 434656 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 434656 T1772 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 434657 T1736 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 434657 T1713 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 434658 T1713 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 434657 T1736 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:45400",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:45400_",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 434660 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 434726 T1713 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 434728 T1713 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60563
[junit4] 2> 434729 T1713 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 434730 T1713 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 434730 T1713 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1386440163784
[junit4] 2> 434730 T1713 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1386440163784/'
[junit4] 2> 434740 T1713 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1386440163784/solr.xml
[junit4] 2> 434765 T1742 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 434765 T1771 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 434765 T1735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 434765 T1755 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 434776 T1713 oasc.CoreContainer.<init> New CoreContainer 1744070219
[junit4] 2> 434777 T1713 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1386440163784/]
[junit4] 2> 434778 T1713 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 434778 T1713 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 434778 T1713 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 434778 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 434779 T1713 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 434779 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 434779 T1713 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 434779 T1713 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 434779 T1713 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 434783 T1713 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 434783 T1713 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 434783 T1713 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 434784 T1713 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60655/solr
[junit4] 2> 434784 T1713 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 434785 T1713 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 434786 T1785 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@36f8c606 name:ZooKeeperConnection Watcher:127.0.0.1:60655 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 434787 T1713 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 434789 T1713 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 434790 T1787 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18e213a9 name:ZooKeeperConnection Watcher:127.0.0.1:60655/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 434790 T1713 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 434793 T1713 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 435796 T1713 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60563_
[junit4] 2> 435797 T1713 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60563_
[junit4] 2> 435800 T1742 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 435800 T1771 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 435800 T1735 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 435800 T1787 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 435800 T1755 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 435805 T1788 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 435805 T1788 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 435806 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 435806 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 435807 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 435806 T1788 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 435808 T1736 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 435808 T1736 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60563",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:60563_",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "shard_parent":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":null}
[junit4] 2> 435808 T1736 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 435808 T1736 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 435811 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 435912 T1787 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 435912 T1771 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 435912 T1755 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 435912 T1742 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 435912 T1735 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 436807 T1788 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 436808 T1788 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1386440163784/collection1
[junit4] 2> 436808 T1788 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 436809 T1788 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 436809 T1788 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 436811 T1788 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1386440163784/collection1/'
[junit4] 2> 436812 T1788 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1386440163784/collection1/lib/classes/' to classloader
[junit4] 2> 436812 T1788 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1386440163784/collection1/lib/README' to classloader
[junit4] 2> 436858 T1788 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 436877 T1788 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 436878 T1788 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 436885 T1788 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 437115 T1788 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 437116 T1788 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 437117 T1788 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 437121 T1788 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 437147 T1788 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 437148 T1788 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-jetty3-1386440163784/collection1/, dataDir=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty3/
[junit4] 2> 437148 T1788 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3877a90f
[junit4] 2> 437149 T1788 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty3
[junit4] 2> 437149 T1788 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty3/index/
[junit4] 2> 437149 T1788 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty3/index' doesn't exist. Creating new index...
[junit4] 2> 437150 T1788 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty3/index
[junit4] 2> 437150 T1788 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=8.7353515625, floorSegmentMB=0.3603515625, forceMergeDeletesPctAllowed=9.585725146992013, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2914254823518492
[junit4] 2> 437153 T1788 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty3/index,segFN=segments_1,generation=1}
[junit4] 2> 437153 T1788 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 437157 T1788 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 437157 T1788 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 437157 T1788 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 437158 T1788 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 437158 T1788 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 437158 T1788 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 437158 T1788 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 437159 T1788 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 437159 T1788 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 437166 T1788 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 437167 T1788 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 437167 T1788 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 437167 T1788 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 437168 T1788 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 437168 T1788 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 437168 T1788 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 437170 T1788 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 437172 T1788 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 437172 T1788 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 437173 T1788 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=14, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 437174 T1788 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.ChaosMonkeyNothingIsSafeTest-1386440156527/jetty3/index,segFN=segments_1,generation=1}
[junit4] 2> 437174 T1788 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 437175 T1788 oass.SolrIndexSearcher.<init> Opening Searcher@7279e99a main
[junit4] 2> 437178 T1789 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7279e99a main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 437179 T1788 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 437179 T1788 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60563 collection:collection1 shard:shard2
[junit4] 2> 437183 T1788 oasc.ZkController.register We are http://127.0.0.1:60563/collection1/ and leader is http://127.0.0.1:59216/collection1/
[junit4] 2> 437183 T1788 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60563
[junit4] 2> 437183 T1788 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 437184 T1788 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C1651 name=collection1 org.apache.solr.core.SolrCore@7fb9820f url=http://127.0.0.1:60563/collection1 node=127.0.0.1:60563_ C1651_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:60563, core=collection1, node_name=127.0.0.1:60563_}
[junit4] 2> 437184 T1790 C1651 P60563 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 437184 T1788 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 437184 T1790 C1651 P60563 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4] 2> 437185 T1790 C1651 P60563 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4] 2> 437185 T1790 C1651 P60563 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 437185 T1713 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J1
[junit4] 2> 437186 T1713 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 437186 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 437186 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fired on path: /overseer/queue state: SyncConnected type NodeChildrenChanged
[junit4] 2> 437186 T1735 oasc.DistributedQueue$LatchChildWatcher.process LatchChildWatcher fi
[...truncated too long message...]
t.locks.LockSupport.park(LockSupport.java:186)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 43) Thread[id=1726, name=qtp931047478-1726 Acceptor0 SelectChannelConnector@127.0.0.1:46733, state=BLOCKED, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] 2> at org.eclipse.jetty.server.nio.SelectChannelConnector.getConnection(SelectChannelConnector.java:160)
[junit4] 2> at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:934)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 44) Thread[id=1919, name=qtp529409445-1919 Acceptor0 SelectChannelConnector@127.0.0.1:45400, state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] 2> at org.eclipse.jetty.server.nio.SelectChannelConnector.accept(SelectChannelConnector.java:102)
[junit4] 2> at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:938)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 45) Thread[id=1927, name=Thread-789-SendThread(localhost.localdomain:60655), state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:984)
[junit4] 2> 46) Thread[id=1786, name=TEST-ChaosMonkeyNothingIsSafeTest.testDistribSearch-seed#[BAA5374BB3E2F423]-SendThread(localhost.localdomain:60655), state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:86)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.startConnect(ClientCnxn.java:937)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:993)
[junit4] 2> 47) Thread[id=1810, name=qtp353055802-1810 Selector0, state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] 2> at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
[junit4] 2> at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
[junit4] 2> at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:79)
[junit4] 2> at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
[junit4] 2> at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectorManager$SelectSet.doSelect(SelectorManager.java:569)
[junit4] 2> at org.eclipse.jetty.io.nio.SelectorManager$1.run(SelectorManager.java:290)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 48) Thread[id=1860, name=searcherExecutor-1387-thread-1, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1068)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1130)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 49) Thread[id=1820, name=TEST-ChaosMonkeyNothingIsSafeTest.testDistribSearch-seed#[BAA5374BB3E2F423]-SendThread(localhost.localdomain:60655), state=TIMED_WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:984)
[junit4] 2> 50) Thread[id=1795, name=qtp1051750389-1795 Acceptor0 SelectChannelConnector@127.0.0.1:58809, state=RUNNABLE, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] 2> at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:956)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 51) Thread[id=1791, name=Thread-744, state=WAITING, group=TGRP-ChaosMonkeyNothingIsSafeTest]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:503)
[junit4] 2> at org.apache.solr.core.CloserThread.run(CoreContainer.java:1006)
[junit4] 2> NOTE: test params are: codec=Lucene42, sim=DefaultSimilarity, locale=sr_ME, timezone=Pacific/Truk
[junit4] 2> NOTE: Linux 3.8.0-34-generic amd64/Oracle Corporation 1.7.0_60-ea (64-bit)/cpus=8,threads=63,free=137767040,total=286859264
[junit4] 2> NOTE: All tests run in this JVM: [SolrRequestParserTest, ZkNodePropsTest, BlockDirectoryTest, SolrTestCaseJ4Test, TestFieldTypeResource, DefaultValueUpdateProcessorTest, TestHashPartitioner, HdfsChaosMonkeySafeLeaderTest, TestSimpleQParserPlugin, TestCloudManagedSchemaAddField, HighlighterTest, TestJmxIntegration, TestUpdate, TestPhraseSuggestions, DistributedQueryComponentCustomSortTest, TestSolrCoreProperties, TestRangeQuery, TestPartialUpdateDeduplication, MultiTermTest, TestDistribDocBasedVersion, TestQuerySenderNoQuery, RequestHandlersTest, SpellCheckCollatorTest, TestSolrQueryParserResource, TestCollationField, BasicDistributedZk2Test, TestWordDelimiterFilterFactory, TestFastLRUCache, TestSolrDeletionPolicy2, TestOmitPositions, DocValuesMissingTest, TestFoldingMultitermQuery, CurrencyFieldXmlFileTest, DirectSolrSpellCheckerTest, TestCollationKeyRangeQueries, TestSolrQueryParserDefaultOperatorResource, RAMDirectoryFactoryTest, TestNumberUtils, SpellCheckComponentTest, DisMaxRequestHandlerTest, CustomCollectionTest, TestDistributedGrouping, SolrIndexConfigTest, TestDynamicFieldCollectionResource, TestFiltering, TestStressReorder, DirectSolrConnectionTest, TestReload, PluginInfoTest, TestFuzzyAnalyzedSuggestions, TestAtomicUpdateErrorCases, HdfsSyncSliceTest, DistanceFunctionTest, TestQueryTypes, SpellingQueryConverterTest, AddBlockUpdateTest, TestFastOutputStream, ShardRoutingCustomTest, OpenExchangeRatesOrgProviderTest, ChaosMonkeyNothingIsSafeTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ChaosMonkeyNothingIsSafeTest -Dtests.seed=BAA5374BB3E2F423 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr_ME -Dtests.timezone=Pacific/Truk -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | ChaosMonkeyNothingIsSafeTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([BAA5374BB3E2F423]:0)
[junit4] Completed on J1 in 7264.11s, 1 test, 2 errors <<< FAILURES!
[...truncated 80 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:426: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:406: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/extra-targets.xml:37: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:189: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:491: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1307: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:940: There were test failures: 347 suites, 1516 tests, 1 suite-level error, 1 error, 111 ignored (6 assumptions)
Total time: 151 minutes 17 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_60-ea-b01 -XX:-UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure