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 2014/02/06 16:51:27 UTC
[JENKINS] Lucene-Solr-4.x-Linux (64bit/ibm-j9-jdk6) - Build # 9277
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/9277/
Java: 64bit/ibm-j9-jdk6 -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.testDupeDetection
Error Message:
expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
at __randomizedtesting.SeedInfo.seed([1A0015DAD8686596:4130EE12DB2FC6EA]: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:60)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
at java.lang.reflect.Method.invoke(Method.java:611)
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:738)
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([1A0015DAD8686596:7B95CF3A30B96F60]: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:60)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
at java.lang.reflect.Method.invoke(Method.java:611)
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:738)
Build Log:
[...truncated 10055 lines...]
[junit4] Suite: org.apache.solr.update.processor.SignatureUpdateProcessorFactoryTest
[junit4] 2> 746396 T2706 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-1391701696340
[junit4] 2> 746398 T2706 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> 746400 T2706 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> 746401 T2706 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> 746456 T2706 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 746504 T2706 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 746513 T2706 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 746525 T2706 oass.IndexSchema.readSchema [null] Schema name=test
[junit4] 2> 746844 T2706 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 746846 T2706 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 746848 T2706 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 746871 T2706 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 746875 T2706 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 746878 T2706 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 746879 T2706 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> 746880 T2706 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> 746890 T2706 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 746890 T2706 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> 746891 T2706 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> 746943 T2706 oasc.CoreContainer.<init> New CoreContainer 1349734515
[junit4] 2> 746943 T2706 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> 746945 T2706 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 746946 T2706 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 746946 T2706 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 746947 T2706 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 746948 T2706 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 746949 T2706 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 746949 T2706 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 746950 T2706 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 746951 T2706 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 746955 T2706 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 746955 T2706 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 746956 T2706 oasc.CoreContainer.load Host Name:
[junit4] 2> 746961 T2707 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> 746962 T2707 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> 746965 T2707 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> 746967 T2707 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> 747013 T2707 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 747068 T2707 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 747069 T2707 oass.IndexSchema.readSchema Reading Solr Schema from schema12.xml
[junit4] 2> 747074 T2707 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 747415 T2707 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 747418 T2707 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 747419 T2707 oass.IndexSchema.readSchema WARN Field text is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 747441 T2707 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 747448 T2707 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 747452 T2707 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 747452 T2707 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-1391701696340/
[junit4] 2> 747452 T2707 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@46e546e5
[junit4] 2> 747453 T2707 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1391701696340
[junit4] 2> 747454 T2707 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-1391701696340/index/
[junit4] 2> 747454 T2707 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-1391701696340/index' doesn't exist. Creating new index...
[junit4] 2> 747455 T2707 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1391701696340/index
[junit4] 2> 747455 T2707 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=43, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1690210199649843]
[junit4] 2> 747456 T2707 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_1,generation=1}
[junit4] 2> 747458 T2707 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 747459 T2707 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 747464 T2707 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 747465 T2707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 747465 T2707 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 747466 T2707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 747466 T2707 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 747467 T2707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 747467 T2707 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 747468 T2707 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> 747468 T2707 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
[junit4] 2> 747469 T2707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 747469 T2707 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 747470 T2707 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 747470 T2707 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 747471 T2707 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 747471 T2707 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 747472 T2707 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 747472 T2707 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 747473 T2707 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 747474 T2707 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 747474 T2707 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 747475 T2707 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 747476 T2707 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 747476 T2707 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 747477 T2707 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 747477 T2707 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 747478 T2707 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 747479 T2707 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 747479 T2707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 747480 T2707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 747481 T2707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 747481 T2707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 747482 T2707 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 747482 T2707 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 747483 T2707 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 747484 T2707 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 747485 T2707 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 747486 T2707 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 747487 T2707 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 747489 T2707 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 747489 T2707 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 747490 T2707 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.740535437270026]
[junit4] 2> 747491 T2707 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_1,generation=1}
[junit4] 2> 747491 T2707 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 747492 T2707 oass.SolrIndexSearcher.<init> Opening Searcher@3dc83dc8[collection1] main
[junit4] 2> 747493 T2707 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 747587 T2707 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 747656 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3dc83dc8[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 747669 T2707 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 747671 T2706 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 747677 T2706 oas.SolrTestCaseJ4.setUp ###Starting testDupeDetection
[junit4] 2> ASYNC NEW_CORE C3088 name=collection1 org.apache.solr.core.SolrCore@34f434f4
[junit4] 2> 747678 T2706 C3088 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 747679 T2706 C3088 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 747686 T2706 C3088 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747688 T2706 C3088 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_2,generation=2}
[junit4] 2> 747689 T2706 C3088 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 747689 T2706 C3088 oass.SolrIndexSearcher.<init> Opening Searcher@b000b[collection1] main
[junit4] 2> 747690 T2706 C3088 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747692 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b000b[collection1] main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 747694 T2706 C3088 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4] 2> 747700 T2706 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747702 T2706 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_3,generation=3}
[junit4] 2> 747703 T2706 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 747704 T2706 oass.SolrIndexSearcher.<init> Opening Searcher@71a371a3[collection1] main
[junit4] 2> 747704 T2706 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747717 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@71a371a3[collection1] main{StandardDirectoryReader(segments_3:4:nrt _0(4.7):C2/1:delGen=1)}
[junit4] 2> 747721 T2706 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747723 T2706 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_4,generation=4}
[junit4] 2> 747723 T2706 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 747725 T2706 oass.SolrIndexSearcher.<init> Opening Searcher@1a731a73[collection1] main
[junit4] 2> 747725 T2706 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747729 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a731a73[collection1] main{StandardDirectoryReader(segments_4:6:nrt _0(4.7):C2/1:delGen=1 _1(4.7):C1)}
[junit4] 2> 747731 T2706 oas.SolrTestCaseJ4.tearDown ###Ending testDupeDetection
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SignatureUpdateProcessorFactoryTest -Dtests.method=testDupeDetection -Dtests.seed=1A0015DAD8686596 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ms -Dtests.timezone=Australia/Sydney -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 0.08s J0 | SignatureUpdateProcessorFactoryTest.testDupeDetection <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([1A0015DAD8686596:4130EE12DB2FC6EA]: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:738)
[junit4] 2> 747753 T2706 oas.SolrTestCaseJ4.setUp ###Starting testNonStringFieldsValues
[junit4] 2> ASYNC NEW_CORE C3089 name=collection1 org.apache.solr.core.SolrCore@34f434f4
[junit4] 2> 747754 T2706 C3089 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 747755 T2706 C3089 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 747757 T2706 C3089 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747757 T2706 C3089 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_4,generation=4}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_5,generation=5}
[junit4] 2> 747758 T2706 C3089 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
[junit4] 2> 747759 T2706 C3089 oass.SolrIndexSearcher.<init> Opening Searcher@6ec26ec2[collection1] main
[junit4] 2> 747760 T2706 C3089 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747764 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6ec26ec2[collection1] main{StandardDirectoryReader(segments_5:7:nrt)}
[junit4] 2> 747765 T2706 C3089 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4] 2> 747770 T2706 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 747777 T2706 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747779 T2706 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_5,generation=5}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_6,generation=6}
[junit4] 2> 747780 T2706 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
[junit4] 2> 747781 T2706 oass.SolrIndexSearcher.<init> Opening Searcher@21772177[collection1] main
[junit4] 2> 747782 T2706 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747786 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@21772177[collection1] main{StandardDirectoryReader(segments_6:9:nrt _2(4.7):C5/1:delGen=1)}
[junit4] 2> 747788 T2706 oas.SolrTestCaseJ4.tearDown ###Ending testNonStringFieldsValues
[junit4] 2> 747801 T2706 oas.SolrTestCaseJ4.setUp ###Starting testDupeAllFieldsDetection
[junit4] 2> 747802 T2706 C3089 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 747803 T2706 C3089 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 2
[junit4] 2> 747805 T2706 C3089 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747806 T2706 C3089 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_6,generation=6}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_7,generation=7}
[junit4] 2> 747807 T2706 C3089 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
[junit4] 2> 747809 T2706 C3089 oass.SolrIndexSearcher.<init> Opening Searcher@435a435a[collection1] main
[junit4] 2> 747809 T2706 C3089 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747825 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@435a435a[collection1] main{StandardDirectoryReader(segments_7:10:nrt)}
[junit4] 2> ASYNC NEW_CORE C3090 name=collection1 org.apache.solr.core.SolrCore@34f434f4
[junit4] 2> 747826 T2706 C3090 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 21
[junit4] 2> 747834 T2706 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747835 T2706 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_7,generation=7}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_8,generation=8}
[junit4] 2> 747837 T2706 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
[junit4] 2> 747839 T2706 oass.SolrIndexSearcher.<init> Opening Searcher@3d573d57[collection1] main
[junit4] 2> 747840 T2706 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747844 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3d573d57[collection1] main{StandardDirectoryReader(segments_8:12:nrt _3(4.7):C3)}
[junit4] 2> 747845 T2706 oas.SolrTestCaseJ4.tearDown ###Ending testDupeAllFieldsDetection
[junit4] 2> 747859 T2706 oas.SolrTestCaseJ4.setUp ###Starting testFailNonIndexedSigWithOverwriteDupes
[junit4] 2> 747860 T2706 C3090 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 747861 T2706 C3090 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 747864 T2706 C3090 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747865 T2706 C3090 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_8,generation=8}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_9,generation=9}
[junit4] 2> 747867 T2706 C3090 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
[junit4] 2> 747868 T2706 C3090 oass.SolrIndexSearcher.<init> Opening Searcher@35ef35ef[collection1] main
[junit4] 2> 747869 T2706 C3090 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747871 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@35ef35ef[collection1] main{StandardDirectoryReader(segments_9:13:nrt)}
[junit4] 2> 747872 T2706 C3090 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 8
[junit4] 2> 747873 T2706 oas.SolrTestCaseJ4.tearDown ###Ending testFailNonIndexedSigWithOverwriteDupes
[junit4] 2> 747881 T2706 oas.SolrTestCaseJ4.setUp ###Starting testNonIndexedSignatureField
[junit4] 2> 747881 T2706 C3090 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 747882 T2706 C3090 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 747883 T2706 C3090 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747884 T2706 C3090 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_9,generation=9}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_a,generation=10}
[junit4] 2> 747885 T2706 C3090 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
[junit4] 2> 747886 T2706 C3090 oass.SolrIndexSearcher.<init> Opening Searcher@2d052d05[collection1] main
[junit4] 2> 747886 T2706 C3090 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747889 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2d052d05[collection1] main{StandardDirectoryReader(segments_a:14:nrt)}
[junit4] 2> 747889 T2706 C3090 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 6
[junit4] 2> 747894 T2706 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747895 T2706 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_a,generation=10}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_b,generation=11}
[junit4] 2> 747895 T2706 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
[junit4] 2> 747897 T2706 oass.SolrIndexSearcher.<init> Opening Searcher@3550355[collection1] main
[junit4] 2> 747897 T2706 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747900 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3550355[collection1] main{StandardDirectoryReader(segments_b:16:nrt _4(4.7):C2)}
[junit4] 2> 747901 T2706 oas.SolrTestCaseJ4.tearDown ###Ending testNonIndexedSignatureField
[junit4] 2> 747916 T2706 oas.SolrTestCaseJ4.setUp ###Starting testMultiThreaded
[junit4] 2> ASYNC NEW_CORE C3091 name=collection1 org.apache.solr.core.SolrCore@34f434f4
[junit4] 2> 747917 T2706 C3091 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 747918 T2706 C3091 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 1
[junit4] 2> 747920 T2706 C3091 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747920 T2706 C3091 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_b,generation=11}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_c,generation=12}
[junit4] 2> 747921 T2706 C3091 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
[junit4] 2> 747922 T2706 C3091 oass.SolrIndexSearcher.<init> Opening Searcher@56395639[collection1] main
[junit4] 2> 747923 T2706 C3091 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747926 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@56395639[collection1] main{StandardDirectoryReader(segments_c:17:nrt)}
[junit4] 2> 747927 T2706 C3091 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 7
[junit4] 2> 747936 T2719 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747941 T2719 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_c,generation=12}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_d,generation=13}
[junit4] 2> 747942 T2719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 13
[junit4] 2> 747946 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@9690969[collection1] main
[junit4] 2> 747948 T2719 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747951 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9690969[collection1] main{StandardDirectoryReader(segments_d:30:nrt _7(4.7):C11/8:delGen=1 _8(4.7):C2/1:delGen=1)}
[junit4] 2> 747952 T2717 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 747968 T2717 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_d,generation=13}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_e,generation=14}
[junit4] 2> 747969 T2717 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 14
[junit4] 2> 747979 T2717 oass.SolrIndexSearcher.<init> Opening Searcher@2d072d07[collection1] main
[junit4] 2> 747984 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2d072d07[collection1] main{StandardDirectoryReader(segments_e:42:nrt _b(4.7):C25/24:delGen=1 _c(4.7):C4/3:delGen=1)}
[junit4] 2> 747986 T2717 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 747987 T2718 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748004 T2718 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_e,generation=14}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_f,generation=15}
[junit4] 2> 748005 T2718 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 15
[junit4] 2> 748015 T2718 oass.SolrIndexSearcher.<init> Opening Searcher@52885288[collection1] main
[junit4] 2> 748015 T2718 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748016 T2719 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748019 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@52885288[collection1] main{StandardDirectoryReader(segments_f:54:nrt _f(4.7):C32/29:delGen=1 _g(4.7):C7/6:delGen=1)}
[junit4] 2> 748025 T2719 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_f,generation=15}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_g,generation=16}
[junit4] 2> 748026 T2719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 16
[junit4] 2> 748030 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@695b695b[collection1] main
[junit4] 2> 748031 T2719 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748031 T2717 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748032 T2717 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_g,generation=16}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_h,generation=17}
[junit4] 2> 748033 T2717 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 17
[junit4] 2> 748033 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@695b695b[collection1] main{StandardDirectoryReader(segments_g:64:nrt _j(4.7):C17/14:delGen=2)}
[junit4] 2> 748034 T2717 oass.SolrIndexSearcher.<init> Opening Searcher@211a211a[collection1] main
[junit4] 2> 748034 T2717 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748035 T2718 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748037 T2718 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_h,generation=17}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_i,generation=18}
[junit4] 2> 748037 T2718 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 18
[junit4] 2> 748037 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@211a211a[collection1] main{StandardDirectoryReader(segments_g:64:nrt _j(4.7):C17/14:delGen=2)}
[junit4] 2> 748038 T2718 oass.SolrIndexSearcher.<init> Opening Searcher@63ae63ae[collection1] main
[junit4] 2> 748039 T2718 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748040 T2719 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748041 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@63ae63ae[collection1] main{StandardDirectoryReader(segments_i:66:nrt _j(4.7):C17/14:delGen=2 _l(4.7):C1)}
[junit4] 2> 748042 T2719 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_i,generation=18}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_j,generation=19}
[junit4] 2> 748043 T2719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 19
[junit4] 2> 748052 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@27392739[collection1] main
[junit4] 2> 748053 T2719 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748053 T2717 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748056 T2717 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_j,generation=19}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_k,generation=20}
[junit4] 2> 748057 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@27392739[collection1] main{StandardDirectoryReader(segments_j:70:nrt _j(4.7):C17/15:delGen=3 _m(4.7):C1)}
[junit4] 2> 748057 T2717 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 20
[junit4] 2> 748059 T2717 oass.SolrIndexSearcher.<init> Opening Searcher@34593459[collection1] main
[junit4] 2> 748059 T2717 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748060 T2718 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748061 T2718 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 748061 T2718 oasc.SolrCore.getSearcher WARN [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
[junit4] 2> 748062 T2718 oass.SolrIndexSearcher.<init> Opening Searcher@5d4c5d4c[collection1] main
[junit4] 2> 748062 T2718 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748113 T2719 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748113 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@34593459[collection1] main{StandardDirectoryReader(segments_k:74:nrt _j(4.7):C17/15:delGen=3 _n(4.7):C1)}
[junit4] 2> 748117 T2719 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_k,generation=20}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_l,generation=21}
[junit4] 2> 748118 T2719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 21
[junit4] 2> 748120 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5d4c5d4c[collection1] main{StandardDirectoryReader(segments_k:74:nrt _j(4.7):C17/15:delGen=3 _n(4.7):C1)}
[junit4] 2> 748122 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@1bc01bc[collection1] main
[junit4] 2> 748123 T2719 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748124 T2717 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748127 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1bc01bc[collection1] main{StandardDirectoryReader(segments_l:83:nrt _j(4.7):C17/16:delGen=3 _p(4.7):C1)}
[junit4] 2> 748127 T2717 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_l,generation=21}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_m,generation=22}
[junit4] 2> 748129 T2717 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 22
[junit4] 2> 748130 T2717 oass.SolrIndexSearcher.<init> Opening Searcher@5d155d15[collection1] main
[junit4] 2> 748130 T2717 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748131 T2718 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748133 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5d155d15[collection1] main{StandardDirectoryReader(segments_m:86:nrt _q(4.7):C1)}
[junit4] 2> 748133 T2718 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_m,generation=22}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_n,generation=23}
[junit4] 2> 748134 T2718 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 23
[junit4] 2> 748135 T2718 oass.SolrIndexSearcher.<init> Opening Searcher@782d782d[collection1] main
[junit4] 2> 748137 T2718 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748138 T2719 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748139 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@782d782d[collection1] main{StandardDirectoryReader(segments_n:90:nrt _r(4.7):C1)}
[junit4] 2> 748140 T2719 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_n,generation=23}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_o,generation=24}
[junit4] 2> 748140 T2719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 24
[junit4] 2> 748141 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@209d209d[collection1] main
[junit4] 2> 748141 T2719 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748142 T2717 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748144 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@209d209d[collection1] main{StandardDirectoryReader(segments_o:94:nrt _s(4.7):C1)}
[junit4] 2> 748144 T2717 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_o,generation=24}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_p,generation=25}
[junit4] 2> 748145 T2717 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 25
[junit4] 2> 748146 T2717 oass.SolrIndexSearcher.<init> Opening Searcher@72307230[collection1] main
[junit4] 2> 748147 T2717 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748147 T2718 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748150 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@72307230[collection1] main{StandardDirectoryReader(segments_p:98:nrt _t(4.7):C1)}
[junit4] 2> 748150 T2718 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_p,generation=25}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_q,generation=26}
[junit4] 2> 748151 T2718 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 26
[junit4] 2> 748153 T2718 oass.SolrIndexSearcher.<init> Opening Searcher@1a0b1a0b[collection1] main
[junit4] 2> 748154 T2718 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748156 T2719 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748158 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a0b1a0b[collection1] main{StandardDirectoryReader(segments_q:102:nrt _u(4.7):C1)}
[junit4] 2> 748208 T2719 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_q,generation=26}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_r,generation=27}
[junit4] 2> 748209 T2719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 27
[junit4] 2> 748211 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@24982498[collection1] main
[junit4] 2> 748216 T2719 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748217 T2717 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748218 T2717 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_r,generation=27}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_s,generation=28}
[junit4] 2> 748219 T2717 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 28
[junit4] 2> 748221 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@24982498[collection1] main{StandardDirectoryReader(segments_r:110:nrt _w(4.7):C1)}
[junit4] 2> 748222 T2717 oass.SolrIndexSearcher.<init> Opening Searcher@456f456f[collection1] main
[junit4] 2> 748223 T2717 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748224 T2718 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748227 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@456f456f[collection1] main{StandardDirectoryReader(segments_r:110:nrt _w(4.7):C1)}
[junit4] 2> 748228 T2718 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_s,generation=28}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_t,generation=29}
[junit4] 2> 748229 T2718 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 29
[junit4] 2> 748240 T2718 oass.SolrIndexSearcher.<init> Opening Searcher@412b412b[collection1] main
[junit4] 2> 748240 T2718 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748241 T2719 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748242 T2719 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_t,generation=29}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_u,generation=30}
[junit4] 2> 748243 T2719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 30
[junit4] 2> 748243 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@412b412b[collection1] main{StandardDirectoryReader(segments_t:118:nrt _y(4.7):C1)}
[junit4] 2> 748244 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@743e743e[collection1] main
[junit4] 2> 748244 T2719 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748245 T2717 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748245 T2717 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 748246 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@743e743e[collection1] main{StandardDirectoryReader(segments_t:118:nrt _y(4.7):C1)}
[junit4] 2> 748248 T2717 oass.SolrIndexSearcher.<init> Opening Searcher@78df78df[collection1] main
[junit4] 2> 748249 T2717 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748250 T2718 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748251 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@78df78df[collection1] main{StandardDirectoryReader(segments_u:123:nrt _z(4.7):C1)}
[junit4] 2> 748252 T2718 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_u,generation=30}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_v,generation=31}
[junit4] 2> 748253 T2718 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 31
[junit4] 2> 748255 T2718 oass.SolrIndexSearcher.<init> Opening Searcher@28732873[collection1] main
[junit4] 2> 748256 T2718 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748257 T2719 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748258 T2719 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_v,generation=31}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_w,generation=32}
[junit4] 2> 748258 T2719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 32
[junit4] 2> 748259 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@28732873[collection1] main{StandardDirectoryReader(segments_v:130:nrt _11(4.7):C1)}
[junit4] 2> 748260 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@67a967a9[collection1] main
[junit4] 2> 748261 T2719 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748261 T2717 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748263 T2717 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_w,generation=32}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_x,generation=33}
[junit4] 2> 748264 T2717 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 33
[junit4] 2> 748263 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@67a967a9[collection1] main{StandardDirectoryReader(segments_v:130:nrt _11(4.7):C1)}
[junit4] 2> 748266 T2717 oass.SolrIndexSearcher.<init> Opening Searcher@4d764d76[collection1] main
[junit4] 2> 748266 T2717 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748267 T2718 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748270 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4d764d76[collection1] main{StandardDirectoryReader(segments_x:134:nrt _12(4.7):C1)}
[junit4] 2> 748270 T2718 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_x,generation=33}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_y,generation=34}
[junit4] 2> 748272 T2718 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 34
[junit4] 2> 748273 T2718 oass.SolrIndexSearcher.<init> Opening Searcher@64c764c7[collection1] main
[junit4] 2> 748274 T2718 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748275 T2719 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748278 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@64c764c7[collection1] main{StandardDirectoryReader(segments_y:138:nrt _13(4.7):C1)}
[junit4] 2> 748278 T2719 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_y,generation=34}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_z,generation=35}
[junit4] 2> 748279 T2719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 35
[junit4] 2> 748280 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@4e844e84[collection1] main
[junit4] 2> 748281 T2719 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748282 T2717 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748285 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4e844e84[collection1] main{StandardDirectoryReader(segments_z:142:nrt _14(4.7):C1)}
[junit4] 2> 748287 T2717 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_z,generation=35}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_10,generation=36}
[junit4] 2> 748288 T2717 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 36
[junit4] 2> 748341 T2717 oass.SolrIndexSearcher.<init> Opening Searcher@78947894[collection1] main
[junit4] 2> 748341 T2717 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748342 T2718 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748343 T2718 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_10,generation=36}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_11,generation=37}
[junit4] 2> 748344 T2718 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 37
[junit4] 2> 748344 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@78947894[collection1] main{StandardDirectoryReader(segments_10:148:nrt _15(4.7):C1 _16(4.7):C1)}
[junit4] 2> 748347 T2718 oass.SolrIndexSearcher.<init> Opening Searcher@7be07be[collection1] main
[junit4] 2> 748348 T2718 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748349 T2719 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748351 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7be07be[collection1] main{StandardDirectoryReader(segments_10:148:nrt _15(4.7):C1 _16(4.7):C1)}
[junit4] 2> 748352 T2719 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_11,generation=37}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_12,generation=38}
[junit4] 2> 748352 T2719 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 38
[junit4] 2> 748405 T2719 oass.SolrIndexSearcher.<init> Opening Searcher@d380d38[collection1] main
[junit4] 2> 748406 T2719 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748407 T2717 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748408 T2717 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_12,generation=38}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@8a308a3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11a811a8)),segFN=segments_13,generation=39}
[junit4] 2> 748409 T2717 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 39
[junit4] 2> 748409 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d380d38[collection1] main{StandardDirectoryReader(segments_12:156:nrt _15(4.7):C1 _18(4.7):C1)}
[junit4] 2> 748410 T2717 oass.SolrIndexSearcher.<init> Opening Searcher@774a774a[collection1] main
[junit4] 2> 748411 T2717 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748419 T2718 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748420 T2718 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 748421 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@774a774a[collection1] main{StandardDirectoryReader(segments_12:156:nrt _15(4.7):C1 _18(4.7):C1)}
[junit4] 2> 748422 T2718 oass.SolrIndexSearcher.<init> Opening Searcher@9e709e7[collection1] main
[junit4] 2> 748423 T2718 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748425 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9e709e7[collection1] main{StandardDirectoryReader(segments_12:156:nrt _15(4.7):C1 _18(4.7):C1)}
[junit4] 2> ASYNC NEW_CORE C3092 name=collection1 org.apache.solr.core.SolrCore@34f434f4
[junit4] 2> 748426 T2706 C3092 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 748427 T2706 C3092 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
[junit4] 2> 748427 T2706 C3092 oass.SolrIndexSearcher.<init> Opening Searcher@5c785c78[collection1] main
[junit4] 2> 748428 T2706 C3092 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 748430 T2708 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5c785c78[collection1] main{StandardDirectoryReader(segments_12:156:nrt _15(4.7):C1 _18(4.7):C1)}
[junit4] 2> 748431 T2706 C3092 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 5
[junit4] 2> 748433 T2706 oas.SolrTestCaseJ4.tearDown ###Ending testMultiThreaded
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SignatureUpdateProcessorFactoryTest -Dtests.method=testMultiThreaded -Dtests.seed=1A0015DAD8686596 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ms -Dtests.timezone=Australia/Sydney -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 0.53s J0 | SignatureUpdateProcessorFactoryTest.testMultiThreaded <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([1A0015DAD8686596:7B95CF3A30B96F60]: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:738)
[junit4] 2> 748440 T2706 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 748441 T2706 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1349734515
[junit4] 2> 748441 T2706 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@34f434f4
[junit4] 2> 748462 T2706 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> 748464 T2706 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 748465 T2706 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 748466 T2706 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 748467 T2706 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 748468 T2706 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 748469 T2706 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1391701696340 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1391701696340;done=false>>]
[junit4] 2> 748469 T2706 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1391701696340
[junit4] 2> 748470 T2706 oasc.CachingDirectoryFactory.closeCacheValue looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1391701696340/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1391701696340/index;done=false>>]
[junit4] 2> 748470 T2706 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0/./solrtest-SignatureUpdateProcessorFactoryTest-1391701696340/index
[junit4] 2> NOTE: test params are: codec=Lucene46: {signatureField=FSTOrd41, weight=MockVariableIntBlock(baseBlockSize=56), name=PostingsFormat(name=FSTOrdPulsing41), id=PostingsFormat(name=FSTOrdPulsing41), ints_is=Lucene41(blocksize=128), v_t=PostingsFormat(name=FSTOrdPulsing41)}, docValues:{}, sim=DefaultSimilarity, locale=ms, timezone=Australia/Sydney
[junit4] 2> NOTE: Linux 3.8.0-35-generic amd64/IBM Corporation 1.6.0 (64-bit)/cpus=8,threads=1,free=12151232,total=86610944
[junit4] 2> NOTE: All tests run in this JVM: [CoreAdminHandlerTest, DocumentBuilderTest, AnalysisAfterCoreReloadTest, PolyFieldTest, WordBreakSolrSpellCheckerTest, TestSchemaVersionResource, TestInitQParser, SliceStateTest, ResourceLoaderTest, TestSolrXmlPersistor, DistribCursorPagingTest, TestCustomSort, SuggesterFSTTest, TestJoin, TestSolrXMLSerializer, TestJmxMonitoredMap, ShardRoutingTest, TestCharFilters, TestSystemIdResolver, TestLFUCache, CacheHeaderTest, BadComponentTest, StatelessScriptUpdateProcessorFactoryTest, TermVectorComponentTest, TestAddFieldRealTimeGet, TestNonNRTOpen, SpatialFilterTest, SolrCoreTest, OverseerRolesTest, QueryResultKeyTest, HdfsChaosMonkeySafeLeaderTest, TestCloudManagedSchemaAddField, SchemaVersionSpecificBehaviorTest, EnumFieldTest, DOMUtilTest, SolrIndexSplitterTest, TestWriterPerf, QueryParsingTest, TestLuceneMatchVersion, SliceStateUpdateTest, TestFreeTextSuggestions, TestPHPSerializedResponseWriter, DistributedTermsComponentTest, TestRealTimeGet, EchoParamsTest, TestDocumentBuilder, ResponseLogComponentTest, BadCopyFieldTest, StressHdfsTest, TestLMJelinekMercerSimilarityFactory, TestRemoteStreaming, TestFunctionQuery, CachingDirectoryFactoryTest, PeerSyncTest, SearchHandlerTest, TestCloudManagedSchema, ChangedSchemaMergeTest, TestSuggestSpellingConverter, ZkCLITest, TestLMDirichletSimilarityFactory, TestNRTOpen, TestImplicitCoreProperties, TestBM25SimilarityFactory, TestMergePolicyConfig, AssignTest, SolrTestCaseJ4Test, TestSolrQueryParser, BasicDistributedZkTest, MBeansHandlerTest, SystemInfoHandlerTest, ReturnFieldsTest, SpellPossibilityIteratorTest, TestSolrQueryParserResource, TestSchemaNameResource, PrimitiveFieldTypeTest, RecoveryZkTest, BasicFunctionalityTest, TestValueSourceCache, DeleteReplicaTest, SampleTest, CircularListTest, FastVectorHighlighterTest, UpdateRequestProcessorFactoryTest, SolrInfoMBeanTest, QueryEqualityTest, TestPostingsSolrHighlighter, ScriptEngineTest, CopyFieldTest, SOLR749Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, SyncSliceTest, OverseerTest, LeaderElectionIntegrationTest, BasicZkTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, LeaderElectionTest, TestZkChroot, ShardRoutingCustomTest, AutoCommitTest, TestFaceting, DistributedSpellCheckComponentTest, ZkControllerTest, TestStressReorder, TestReload, HardAutoCommitTest, TestCoreContainer, StatsComponentTest, TestGroupingSearch, DirectUpdateHandlerTest, OverseerCollectionProcessorTest, HighlighterTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, SimplePostToolTest, SignatureUpdateProcessorFactoryTest]
[junit4] Completed on J0 in 2.12s, 6 tests, 2 failures <<< FAILURES!
[...truncated 423 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:459: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:452: 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:1308: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:941: There were test failures: 358 suites, 1554 tests, 2 failures, 69 ignored (7 assumptions)
Total time: 39 minutes 0 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/ibm-j9-jdk6 -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 (32bit/jdk1.8.0-fcs-b128) - Build #
9278 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/9278/
Java: 32bit/jdk1.8.0-fcs-b128 -server -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.testDistribSearch
Error Message:
Expected the partialResults header to be null
Stack Trace:
java.lang.AssertionError: Expected the partialResults header to be null
at __randomizedtesting.SeedInfo.seed([E8407FD57D3AB934:69A6F1CD0A65D908]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.TestDistributedSearch.validateControlData(TestDistributedSearch.java:525)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:445)
at org.apache.solr.TestDistributedSearch.doTest(TestDistributedSearch.java:379)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:873)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
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:744)
Build Log:
[...truncated 10564 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> 873532 T5340 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /rw_beu/b
[junit4] 2> 873535 T5340 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 873536 T5340 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 873538 T5340 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:59251
[junit4] 2> 873540 T5340 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 873540 T5340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 873541 T5340 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> 873541 T5340 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> 873557 T5340 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 873577 T5340 oasc.CoreContainer.<init> New CoreContainer 6114172
[junit4] 2> 873577 T5340 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> 873578 T5340 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 873578 T5340 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 873579 T5340 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 873579 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 873580 T5340 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 873580 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 873581 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 873581 T5340 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 873581 T5340 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 873584 T5340 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 873584 T5340 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 873585 T5340 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 873587 T5350 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> 873588 T5350 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> 873588 T5350 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> 873588 T5350 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> 873609 T5350 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 873629 T5350 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 873630 T5350 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 873634 T5350 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 873748 T5350 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 873749 T5350 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 873749 T5350 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 873754 T5350 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 873755 T5350 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 873761 T5350 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 873762 T5350 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 873763 T5350 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 873764 T5350 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 873764 T5350 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 873764 T5350 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 873764 T5350 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 873765 T5350 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 873765 T5350 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 873765 T5350 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=./org.apache.solr.TestDistributedSearch-1391707147168/control/data/
[junit4] 2> 873765 T5350 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b37a55
[junit4] 2> 873766 T5350 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391707147168/control/data
[junit4] 2> 873766 T5350 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.TestDistributedSearch-1391707147168/control/data/index/
[junit4] 2> 873766 T5350 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.TestDistributedSearch-1391707147168/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 873766 T5350 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391707147168/control/data/index
[junit4] 2> 873767 T5350 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 873767 T5350 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_1,generation=1}
[junit4] 2> 873767 T5350 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 873767 T5350 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 873769 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 873769 T5350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 873769 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 873770 T5350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 873770 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 873770 T5350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 873770 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 873770 T5350 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> 873770 T5350 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
[junit4] 2> 873771 T5350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 873771 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 873771 T5350 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 873771 T5350 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 873771 T5350 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 873772 T5350 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 873772 T5350 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 873772 T5350 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 873772 T5350 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 873773 T5350 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 873773 T5350 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 873773 T5350 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 873773 T5350 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 873774 T5350 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 873774 T5350 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 873774 T5350 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 873774 T5350 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 873774 T5350 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 873775 T5350 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 873775 T5350 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 873775 T5350 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 873775 T5350 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 873776 T5350 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 873776 T5350 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 873776 T5350 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 873776 T5350 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 873777 T5350 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 873778 T5350 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 873779 T5350 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 873780 T5350 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 873780 T5350 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 873781 T5350 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=77.357421875, floorSegmentMB=1.74609375, forceMergeDeletesPctAllowed=14.463724565465968, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6060744454666547
[junit4] 2> 873781 T5350 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_1,generation=1}
[junit4] 2> 873781 T5350 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 873781 T5350 oass.SolrIndexSearcher.<init> Opening Searcher@18f9f05[collection1] main
[junit4] 2> 873782 T5350 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 873895 T5350 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 873918 T5351 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@18f9f05[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 873921 T5350 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 873922 T5340 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 873922 T5340 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 873924 T5340 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 873925 T5340 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:42626
[junit4] 2> 873926 T5340 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 873926 T5340 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 873927 T5340 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> 873927 T5340 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> 873942 T5340 oasc.ConfigSolr.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test-files/solr/solr.xml
[junit4] 2> 873952 T5340 oasc.CoreContainer.<init> New CoreContainer 9916478
[junit4] 2> 873952 T5340 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> 873953 T5340 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 873954 T5340 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 873954 T5340 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 873954 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 873955 T5340 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 873955 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 873956 T5340 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 873956 T5340 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 873957 T5340 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 873959 T5340 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 873960 T5340 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 873960 T5340 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 873962 T5362 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> 873962 T5362 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> 873964 T5362 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> 873964 T5362 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> 874000 T5362 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
[junit4] 2> 874021 T5362 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 874022 T5362 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 874026 T5362 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 874167 T5362 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 874167 T5362 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 874167 T5362 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 874171 T5362 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 874172 T5362 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 874176 T5362 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 874177 T5362 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 874178 T5362 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 874179 T5362 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 874179 T5362 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 874179 T5362 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 874179 T5362 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 874180 T5362 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 874180 T5362 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 874180 T5362 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=./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/
[junit4] 2> 874180 T5362 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@b37a55
[junit4] 2> 874181 T5362 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data
[junit4] 2> 874181 T5362 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index/
[junit4] 2> 874181 T5362 oasc.SolrCore.initIndex WARN [collection1] Solr index directory './org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index' doesn't exist. Creating new index...
[junit4] 2> 874181 T5362 oasc.CachingDirectoryFactory.get return new directory for ./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index
[junit4] 2> 874182 T5362 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=39, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 874182 T5362 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_1,generation=1}
[junit4] 2> 874182 T5362 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 874183 T5362 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 874184 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 874185 T5362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 874185 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 874185 T5362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 874185 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 874185 T5362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 874185 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 874186 T5362 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> 874186 T5362 oasup.UniqFieldsUpdateProcessorFactory.init Replacing 'fields' init param with (individual) 'fieldName' params
[junit4] 2> 874186 T5362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 874186 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 874186 T5362 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 874187 T5362 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 874187 T5362 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 874187 T5362 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 874187 T5362 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 874188 T5362 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 874188 T5362 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 874188 T5362 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 874188 T5362 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 874189 T5362 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 874189 T5362 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 874189 T5362 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 874189 T5362 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 874190 T5362 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 874190 T5362 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 874190 T5362 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 874191 T5362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 874191 T5362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 874191 T5362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 874191 T5362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 874192 T5362 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 874192 T5362 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 874192 T5362 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 874193 T5362 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 874193 T5362 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 874194 T5362 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 874195 T5362 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 874196 T5362 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 874197 T5362 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 874197 T5362 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=77.357421875, floorSegmentMB=1.74609375, forceMergeDeletesPctAllowed=14.463724565465968, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6060744454666547
[junit4] 2> 874198 T5362 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_1,generation=1}
[junit4] 2> 874198 T5362 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 874199 T5362 oass.SolrIndexSearcher.<init> Opening Searcher@142f062[collection1] main
[junit4] 2> 874199 T5362 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 874203 T5362 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 874219 T5363 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@142f062[collection1] main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 874222 T5362 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 874223 T5340 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-core/test/J0
[junit4] 2> 874223 T5340 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C1813 name=collection1 org.apache.solr.core.SolrCore@1dd47
[junit4] 2> 874225 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1459310714272350208)} 0 0
[junit4] 2> ASYNC NEW_CORE C1814 name=collection1 org.apache.solr.core.SolrCore@b4c1dd
[junit4] 2> 874227 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1459310714273398784)} 0 1
[junit4] 2> 874231 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[1 (1459310714276544512)]} 0 2
[junit4] 2> 874234 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[1 (1459310714280738816)]} 0 1
[junit4] 2> 874237 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[2 (1459310714283884544)]} 0 1
[junit4] 2> 874239 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[2 (1459310714287030272)]} 0 0
[junit4] 2> 874242 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[3 (1459310714289127424)]} 0 1
[junit4] 2> 874245 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[3 (1459310714292273152)]} 0 1
[junit4] 2> 874247 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[4 (1459310714295418880)]} 0 0
[junit4] 2> 874250 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[4 (1459310714297516032)]} 0 1
[junit4] 2> 874252 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[5 (1459310714300661760)]} 0 0
[junit4] 2> 874255 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[5 (1459310714302758912)]} 0 1
[junit4] 2> 874258 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[6 (1459310714305904640)]} 0 1
[junit4] 2> 874260 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[6 (1459310714309050368)]} 0 0
[junit4] 2> 874263 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[7 (1459310714311147520)]} 0 1
[junit4] 2> 874266 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[7 (1459310714314293248)]} 0 1
[junit4] 2> 874268 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[8 (1459310714317438976)]} 0 0
[junit4] 2> 874271 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[8 (1459310714319536128)]} 0 1
[junit4] 2> 874273 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[9 (1459310714321633280)]} 0 1
[junit4] 2> 874276 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[9 (1459310714324779008)]} 0 1
[junit4] 2> 874278 T5346 C1813 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 874283 T5346 C1813 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_2,generation=2}
[junit4] 2> 874284 T5346 C1813 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 874285 T5346 C1813 oass.SolrIndexSearcher.<init> Opening Searcher@1e2b085[collection1] main
[junit4] 2> 874286 T5346 C1813 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 874287 T5351 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e2b085[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):C9)}
[junit4] 2> 874288 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 10
[junit4] 2> 874290 T5360 C1814 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 874293 T5360 C1814 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_2,generation=2}
[junit4] 2> 874294 T5360 C1814 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 874296 T5360 C1814 oass.SolrIndexSearcher.<init> Opening Searcher@e746ef[collection1] main
[junit4] 2> 874297 T5363 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@e746ef[collection1] main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):C9)}
[junit4] 2> 874298 T5360 C1814 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 874298 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 8
[junit4] 2> 874302 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[10 (1459310714350993408)]} 0 2
[junit4] 2> 874307 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[10 (1459310714356236288)]} 0 3
[junit4] 2> 874309 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[11 (1459310714360430592)]} 0 0
[junit4] 2> 874312 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[11 (1459310714363576320)]} 0 0
[junit4] 2> 874315 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[12 (1459310714366722048)]} 0 0
[junit4] 2> 874318 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[12 (1459310714368819200)]} 0 1
[junit4] 2> 874320 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[13 (1459310714371964928)]} 0 0
[junit4] 2> 874323 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[13 (1459310714374062080)]} 0 1
[junit4] 2> 874326 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[1001 (1459310714377207808)]} 0 1
[junit4] 2> 874329 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[1001 (1459310714380353536)]} 0 1
[junit4] 2> 874331 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[14 (1459310714383499264)]} 0 0
[junit4] 2> 874334 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[14 (1459310714385596416)]} 0 1
[junit4] 2> 874336 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[15 (1459310714388742144)]} 0 0
[junit4] 2> 874339 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[15 (1459310714390839296)]} 0 1
[junit4] 2> 874341 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[16 (1459310714392936448)]} 0 1
[junit4] 2> 874344 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[16 (1459310714396082176)]} 0 1
[junit4] 2> 874348 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[17 (1459310714399227904)]} 0 2
[junit4] 2> 874352 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[17 (1459310714403422208)]} 0 2
[junit4] 2> 874354 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[100 (1459310714407616512)]} 0 0
[junit4] 2> 874357 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[100 (1459310714409713664)]} 0 1
[junit4] 2> 874359 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[101 (1459310714412859392)]} 0 0
[junit4] 2> 874362 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[101 (1459310714414956544)]} 0 1
[junit4] 2> 874365 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[102 (1459310714418102272)]} 0 1
[junit4] 2> 874367 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[102 (1459310714421248000)]} 0 0
[junit4] 2> 874370 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[103 (1459310714424393728)]} 0 0
[junit4] 2> 874373 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[103 (1459310714426490880)]} 0 1
[junit4] 2> 874375 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[104 (1459310714429636608)]} 0 0
[junit4] 2> 874377 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[104 (1459310714431733760)]} 0 0
[junit4] 2> 874380 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[105 (1459310714434879488)]} 0 1
[junit4] 2> 874382 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[105 (1459310714436976640)]} 0 0
[junit4] 2> 874385 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[106 (1459310714439073792)]} 0 1
[junit4] 2> 874387 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[106 (1459310714442219520)]} 0 0
[junit4] 2> 874390 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[107 (1459310714444316672)]} 0 1
[junit4] 2> 874392 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[107 (1459310714447462400)]} 0 0
[junit4] 2> 874395 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[108 (1459310714449559552)]} 0 1
[junit4] 2> 874397 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[108 (1459310714452705280)]} 0 1
[junit4] 2> 874399 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[109 (1459310714454802432)]} 0 0
[junit4] 2> 874402 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[109 (1459310714456899584)]} 0 1
[junit4] 2> 874404 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[110 (1459310714460045312)]} 0 0
[junit4] 2> 874406 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[110 (1459310714462142464)]} 0 0
[junit4] 2> 874409 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[111 (1459310714464239616)]} 0 1
[junit4] 2> 874411 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[111 (1459310714467385344)]} 0 1
[junit4] 2> 874413 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[112 (1459310714469482496)]} 0 0
[junit4] 2> 874416 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[112 (1459310714471579648)]} 0 1
[junit4] 2> 874418 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[113 (1459310714474725376)]} 0 0
[junit4] 2> 874421 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[113 (1459310714476822528)]} 0 1
[junit4] 2> 874423 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[114 (1459310714478919680)]} 0 1
[junit4] 2> 874425 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[114 (1459310714482065408)]} 0 0
[junit4] 2> 874428 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[115 (1459310714484162560)]} 0 1
[junit4] 2> 874430 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[115 (1459310714487308288)]} 0 1
[junit4] 2> 874432 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[116 (1459310714489405440)]} 0 0
[junit4] 2> 874435 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[116 (1459310714491502592)]} 0 1
[junit4] 2> 874437 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[117 (1459310714494648320)]} 0 0
[junit4] 2> 874440 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[117 (1459310714496745472)]} 0 1
[junit4] 2> 874442 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[118 (1459310714499891200)]} 0 0
[junit4] 2> 874445 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[118 (1459310714501988352)]} 0 1
[junit4] 2> 874447 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[119 (1459310714505134080)]} 0 1
[junit4] 2> 874449 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[119 (1459310714507231232)]} 0 0
[junit4] 2> 874452 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[120 (1459310714509328384)]} 0 1
[junit4] 2> 874454 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[120 (1459310714512474112)]} 0 0
[junit4] 2> 874457 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[121 (1459310714514571264)]} 0 1
[junit4] 2> 874459 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[121 (1459310714517716992)]} 0 0
[junit4] 2> 874462 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[122 (1459310714519814144)]} 0 1
[junit4] 2> 874464 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[122 (1459310714521911296)]} 0 1
[junit4] 2> 874466 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[123 (1459310714525057024)]} 0 0
[junit4] 2> 874469 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[123 (1459310714527154176)]} 0 1
[junit4] 2> 874471 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[124 (1459310714530299904)]} 0 0
[junit4] 2> 874473 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[124 (1459310714532397056)]} 0 0
[junit4] 2> 874476 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[125 (1459310714534494208)]} 0 1
[junit4] 2> 874478 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[125 (1459310714537639936)]} 0 1
[junit4] 2> 874481 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[126 (1459310714540785664)]} 0 0
[junit4] 2> 874484 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[126 (1459310714542882816)]} 0 1
[junit4] 2> 874487 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[127 (1459310714546028544)]} 0 1
[junit4] 2> 874489 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[127 (1459310714549174272)]} 0 1
[junit4] 2> 874491 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[128 (1459310714551271424)]} 0 0
[junit4] 2> 874494 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[128 (1459310714553368576)]} 0 1
[junit4] 2> 874496 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[129 (1459310714556514304)]} 0 0
[junit4] 2> 874498 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[129 (1459310714558611456)]} 0 0
[junit4] 2> 874500 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[130 (1459310714560708608)]} 0 0
[junit4] 2> 874502 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[130 (1459310714562805760)]} 0 0
[junit4] 2> 874504 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[131 (1459310714564902912)]} 0 0
[junit4] 2> 874506 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[131 (1459310714567000064)]} 0 0
[junit4] 2> 874509 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[132 (1459310714569097216)]} 0 1
[junit4] 2> 874511 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[132 (1459310714572242944)]} 0 0
[junit4] 2> 874514 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[133 (1459310714574340096)]} 0 1
[junit4] 2> 874516 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[133 (1459310714577485824)]} 0 0
[junit4] 2> 874519 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[134 (1459310714580631552)]} 0 1
[junit4] 2> 874521 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[134 (1459310714582728704)]} 0 0
[junit4] 2> 874532 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[135 (1459310714593214464)]} 0 1
[junit4] 2> 874536 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[135 (1459310714597408768)]} 0 1
[junit4] 2> 874538 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[136 (1459310714600554496)]} 0 0
[junit4] 2> 874541 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[136 (1459310714603700224)]} 0 1
[junit4] 2> 874544 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[137 (1459310714605797376)]} 0 1
[junit4] 2> 874546 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[137 (1459310714608943104)]} 0 0
[junit4] 2> 874549 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[138 (1459310714611040256)]} 0 1
[junit4] 2> 874551 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[138 (1459310714613137408)]} 0 1
[junit4] 2> 874560 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[139 (1459310714622574592)]} 0 1
[junit4] 2> 874564 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[139 (1459310714626768896)]} 0 1
[junit4] 2> 874567 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[140 (1459310714629914624)]} 0 1
[junit4] 2> 874569 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[140 (1459310714633060352)]} 0 0
[junit4] 2> 874572 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[141 (1459310714635157504)]} 0 1
[junit4] 2> 874574 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[141 (1459310714638303232)]} 0 0
[junit4] 2> 874577 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[142 (1459310714640400384)]} 0 1
[junit4] 2> 874579 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[142 (1459310714643546112)]} 0 0
[junit4] 2> 874582 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[143 (1459310714645643264)]} 0 1
[junit4] 2> 874584 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[143 (1459310714648788992)]} 0 0
[junit4] 2> 874587 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[144 (1459310714650886144)]} 0 1
[junit4] 2> 874608 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[144 (1459310714672906240)]} 0 1
[junit4] 2> 874610 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[145 (1459310714676051968)]} 0 0
[junit4] 2> 874613 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[145 (1459310714679197696)]} 0 0
[junit4] 2> 874616 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[146 (1459310714681294848)]} 0 1
[junit4] 2> 874618 T5359 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[146 (1459310714684440576)]} 0 0
[junit4] 2> 874620 T5349 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[147 (1459310714686537728)]} 0 0
[junit4] 2> 874623 T5358 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[147 (1459310714688634880)]} 0 1
[junit4] 2> 874625 T5346 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[148 (1459310714691780608)]} 0 0
[junit4] 2> 874627 T5360 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[148 (1459310714693877760)]} 0 0
[junit4] 2> 874630 T5348 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[149 (1459310714695974912)]} 0 1
[junit4] 2> 874632 T5361 C1814 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={wt=javabin&version=2} {add=[149 (1459310714699120640)]} 0 0
[junit4] 2> 874634 T5347 C1813 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 874645 T5347 C1813 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@14a1cac lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1211342)),segFN=segments_3,generation=3}
[junit4] 2> 874646 T5347 C1813 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 874647 T5347 C1813 oass.SolrIndexSearcher.<init> Opening Searcher@1ea50e9[collection1] main
[junit4] 2> 874648 T5347 C1813 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 874649 T5351 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ea50e9[collection1] main{StandardDirectoryReader(segments_3:5:nrt _0(4.7):C9 _1(4.7):C59)}
[junit4] 2> 874650 T5347 C1813 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 16
[junit4] 2> 874652 T5359 C1814 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> ASYNC NEW_CORE C1815 name=collection1 org.apache.solr.core.SolrCore@b4c1dd
[junit4] 2> 874675 T5359 C1815 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RAMDirectory@1beb114 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14e8e16)),segFN=segments_3,generation=3}
[junit4] 2> 874676 T5359 C1815 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 874677 T5359 C1815 oass.SolrIndexSearcher.<init> Opening Searcher@8e7ca1[collection1] main
[junit4] 2> 874677 T5359 C1815 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 874679 T5363 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@8e7ca1[collection1] main{StandardDirectoryReader(segments_3:5:nrt _0(4.7):C9 _1(4.7):C59)}
[junit4] 2> 874679 T5359 C1815 oasup.LogUpdateProcessor.finish [collection1] webapp=/rw_beu/b path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 27
[junit4] 2> ASYNC NEW_CORE C1816 name=collection1 org.apache.solr.core.SolrCore@1dd47
[junit4] 2> 874682 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 874897 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148318&start=0&fsv=true&isShard=true&sort=n_ti1+desc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 874906 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148318&ids=146,124,103,147,136,17,128,8,119,108&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 874910 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=225
[junit4] 2> 874912 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 874917 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148548&start=0&fsv=true&isShard=true&sort=n_ti1+asc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 874920 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148548&ids=11,143,144,100,13,4,137,126,107,109&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 874923 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 874926 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 874930 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148561&start=0&fsv=true&isShard=true&sort=n_f1+desc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 874933 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148561&ids=144,133,112,123,101,15,148,149,1001,107&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 874935 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=7
[junit4] 2> 874937 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875141 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148573&start=0&fsv=true&isShard=true&sort=n_f1+asc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875144 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148573&ids=1,13,2,4,5,115,128,8,10,120&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875145 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=205
[junit4] 2> 875148 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875153 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148784&start=0&fsv=true&isShard=true&sort=n_tf1+desc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875155 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148784&ids=1,14,15,148,16,126,1001,7,129,107&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875157 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 875159 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875164 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148795&start=0&fsv=true&isShard=true&sort=n_tf1+asc&shard.url=[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875167 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148795&ids=110,121,143,13,113,4,128,139,140,130&isShard=true&shard.url=[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 875168 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 875171 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875176 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148807&start=0&fsv=true&isShard=true&sort=n_d1+desc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875179 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148807&ids=121,122,12,113,114,136,105,118,140,142&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875180 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 875182 T5348 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875186 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148818&start=0&fsv=true&isShard=true&sort=n_d1+asc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875188 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148818&ids=110,135,125,115,5,104,127,7,10,131&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875190 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 875192 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875461 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707148827&start=0&fsv=true&isShard=true&sort=n_td1+desc&shard.url=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875464 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707148827&ids=132,122,101,125,5,116,138,128,139,131&isShard=true&shard.url=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 875465 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=271
[junit4] 2> 875468 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875472 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149103&start=0&fsv=true&isShard=true&sort=n_td1+asc&shard.url=[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875475 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149103&ids=144,112,3,124,136,103,149,17,119,142&isShard=true&shard.url=[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 875476 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 875478 T5348 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875483 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149114&start=0&fsv=true&isShard=true&sort=n_l1+desc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875485 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149114&ids=123,13,14,102,4,5,138,6,116,8&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875486 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 875489 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875493 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149124&start=0&fsv=true&isShard=true&sort=n_l1+asc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875495 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149124&ids=110,144,111,125,114,126,1001,129,119,10&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875496 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 875499 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875503 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149134&start=0&fsv=true&isShard=true&sort=n_tl1+desc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875508 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149134&ids=143,11,133,124,114,4,126,115,9,130&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 875509 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 875512 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875516 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149147&start=0&fsv=true&isShard=true&sort=n_tl1+asc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875519 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149147&ids=122,134,136,149,105,17,7,1001,141,142&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875520 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 875523 T5348 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875528 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149159&start=0&fsv=true&isShard=true&sort=n_dt1+desc&shard.url=[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875533 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149159&ids=11,100,112,145,101,113,135,128,106,130&isShard=true&shard.url=[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 875534 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 875536 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875541 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149172&start=0&fsv=true&isShard=true&sort=n_dt1+asc&shard.url=[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875544 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149172&ids=143,102,103,125,7,1001,117,129,8,107&isShard=true&shard.url=[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875545 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::114]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 875548 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875552 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149183&start=0&fsv=true&isShard=true&sort=n_tdt1+desc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875555 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149183&ids=100,113,137,104,6,139,8,107,9,130&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 875556 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 875558 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875563 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149194&start=0&fsv=true&isShard=true&sort=n_tdt1+asc&shard.url=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875566 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149194&ids=110,143,144,133,101,14,136,7,108,131&isShard=true&shard.url=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 875567 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::213]:33332/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 875570 T5348 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875577 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149208&start=0&fsv=true&isShard=true&sort=a_si+desc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875580 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149208&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875581 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 875588 T5346 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort={!func}testfunc(add(a_si,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875593 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149224&start=0&fsv=true&isShard=true&sort={!func}testfunc(add(a_si,5))+desc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875605 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149224&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875606 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b|[ff01::083]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&sort={!func}testfunc(add(a_si,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=15
[junit4] 2> 875610 T5349 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&sort=a_si+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875615 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id&NOW=1391707149246&start=0&fsv=true&isShard=true&sort=a_si+asc&shard.url=127.0.0.1:42626/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875618 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&NOW=1391707149246&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875619 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=127.0.0.1:42626/rw_beu/b&sort=a_si+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 875622 T5347 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=*,score&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875627 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id,score&NOW=1391707149258&start=0&fsv=true&isShard=true&sort=a_si+desc&shard.url=[ff01::083]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875630 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=*,score&NOW=1391707149258&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::083]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875631 T5361 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&shards=[ff01::083]:33332/rw_beu/b|127.0.0.1:42626/rw_beu/b|[ff01::114]:33332/rw_beu/b|[ff01::213]:33332/rw_beu/b&fl=*,score&sort=a_si+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 875634 T5348 C1816 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=1
[junit4] 2> 875638 T5359 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=id,score&NOW=1391707149269&start=0&fsv=true&isShard=true&sort=n_tl1+asc&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 875640 T5360 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/select params={q=*:*&distrib=false&fl=*,score&NOW=1391707149269&ids=122,134,136,149,105,17,7,1001,141,142&isShard=true&shard.url=127.0.0.1:42626/rw_beu/b|[ff01::213]:33332/rw_beu/b|[ff01::114]:33332/rw_beu/b&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 875641 T5358 C1815 oasc.SolrCore.execute [collection1] webapp=/rw_beu/b path=/sele
[...truncated too long message...]
40 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index;done=false>>]
[junit4] 2> 887932 T5340 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391707147168/shard0/data/index
[junit4] 2> 887934 T5340 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/rw_beu/b,null}
[junit4] 2> 888005 T5340 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=17848161
[junit4] 2> 888006 T5340 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@bdc0aa
[junit4] 2> 888015 T5340 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=25,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=6959,transaction_logs_total_number=3}
[junit4] 2> 888015 T5340 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 888016 T5340 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 888016 T5340 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 888019 T5340 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 888020 T5340 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 888020 T5340 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data/index [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data/index;done=false>>]
[junit4] 2> 888021 T5340 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data/index
[junit4] 2> 888021 T5340 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data;done=false>>]
[junit4] 2> 888022 T5340 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391707147168/shard1/data
[junit4] 2> 888023 T5340 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/rw_beu/b,null}
[junit4] 2> 888095 T5340 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=26665611
[junit4] 2> 888096 T5340 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@196f9c
[junit4] 2> 888110 T5340 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=23,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=5719,transaction_logs_total_number=3}
[junit4] 2> 888111 T5340 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 888111 T5340 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 888112 T5340 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 888119 T5340 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 888120 T5340 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 888120 T5340 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data/index [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data/index;done=false>>]
[junit4] 2> 888121 T5340 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data/index
[junit4] 2> 888131 T5340 oasc.CachingDirectoryFactory.closeCacheValue looking to close ./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data [CachedDir<<refCount=0;path=./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data;done=false>>]
[junit4] 2> 888132 T5340 oasc.CachingDirectoryFactory.close Closing directory: ./org.apache.solr.TestDistributedSearch-1391707147168/shard2/data
[junit4] 2> 888133 T5340 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/rw_beu/b,null}
[junit4] 2> 888205 T5340 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=testDistribSearch -Dtests.seed=E8407FD57D3AB934 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_CL -Dtests.timezone=Australia/Lord_Howe -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 14.7s J0 | TestDistributedSearch.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected the partialResults header to be null
[junit4] > at __randomizedtesting.SeedInfo.seed([E8407FD57D3AB934:69A6F1CD0A65D908]:0)
[junit4] > at org.apache.solr.TestDistributedSearch.validateControlData(TestDistributedSearch.java:525)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:445)
[junit4] > at org.apache.solr.TestDistributedSearch.doTest(TestDistributedSearch.java:379)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:873)
[junit4] > at java.lang.Thread.run(Thread.java:744)
[junit4] 2> 888242 T5340 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene45, sim=DefaultSimilarity, locale=es_CL, timezone=Australia/Lord_Howe
[junit4] 2> NOTE: Linux 3.8.0-35-generic i386/Oracle Corporation 1.8.0 (32-bit)/cpus=8,threads=2,free=50053312,total=269234176
[junit4] 2> NOTE: All tests run in this JVM: [ConnectionManagerTest, LegacyHTMLStripCharFilterTest, TestPseudoReturnFields, TestDocBasedVersionConstraints, DistributedSpellCheckComponentTest, TestCoreContainer, DistributedSuggestComponentTest, LeaderElectionTest, ZkCLITest, FastVectorHighlighterTest, TestDynamicFieldResource, TestSuggestSpellingConverter, TestLFUCache, SliceStateTest, OverseerCollectionProcessorTest, LukeRequestHandlerTest, TestRemoteStreaming, SoftAutoCommitTest, TestQueryUtils, AutoCommitTest, TestSearchPerf, StatelessScriptUpdateProcessorFactoryTest, AnalysisErrorHandlingTest, CacheHeaderTest, TestRandomMergePolicy, AliasIntegrationTest, CoreAdminHandlerTest, UnloadDistributedZkTest, TestStandardQParsers, TestSolrQueryParserResource, DeleteShardTest, ResourceLoaderTest, QueryEqualityTest, TestElisionMultitermQuery, UpdateRequestProcessorFactoryTest, AddBlockUpdateTest, TestCharFilters, TestJmxMonitoredMap, TestFieldCollectionResource, SystemInfoHandlerTest, HdfsUnloadDistributedZkTest, PolyFieldTest, ExternalFileFieldSortTest, TestSurroundQueryParser, TestPostingsSolrHighlighter, PrimitiveFieldTypeTest, TestFunctionQuery, SuggesterTest, TestReplicationHandler, TestExtendedDismaxParser, SimpleFacetsTest, MoreLikeThisHandlerTest, TestDocumentBuilder, TestNonNRTOpen, TestPHPSerializedResponseWriter, TestSolrDeletionPolicy1, TestMergePolicyConfig, FieldMutatingUpdateProcessorTest, DocumentBuilderTest, TestLMDirichletSimilarityFactory, DocValuesTest, RegexBoostProcessorTest, TestInfoStreamLogging, UUIDFieldTest, PeerSyncTest, SOLR749Test, TestDistribDocBasedVersion, HdfsCollectionsAPIDistributedZkTest, ZkControllerTest, ParsingFieldUpdateProcessorsTest, TestFaceting, TestCollationFieldDocValues, WordBreakSolrSpellCheckerTest, CopyFieldTest, ScriptEngineTest, BasicFunctionalityTest, HdfsDirectoryTest, TermVectorComponentTest, TermsComponentTest, SolrCoreTest, TestLMJelinekMercerSimilarityFactory, QueryParsingTest, TestFieldTypeResource, IndexSchemaTest, BadComponentTest, TestLRUCache, BasicDistributedZkTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, SyncSliceTest, OverseerTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, ZkSolrClientTest, SliceStateUpdateTest, TestDistributedSearch]
[junit4] Completed on J0 in 14.73s, 1 test, 1 failure <<< FAILURES!
[...truncated 501 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:459: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:439: 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:1308: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:941: There were test failures: 358 suites, 1554 tests, 1 failure, 35 ignored (7 assumptions)
Total time: 46 minutes 36 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-fcs-b128 -server -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure