You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/11/23 23:05:49 UTC

[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0-ea-b116) - Build # 3499 - Still Failing!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3499/
Java: 32bit/jdk1.8.0-ea-b116 -client -XX:+UseConcMarkSweepGC

1 tests failed.
REGRESSION:  org.apache.solr.analytics.NoFacetTest.stddevTest

Error Message:
Oops: (double raws) 4631318898052956160 - 4628496337733101339 < 4442235333156365461 Calculated diff 4625071700926640586 Let's see what the JVM thinks these bits are. FloatResult:  43.5 floatTest: 27.72225134241226 Diff 15.77774865758774

Stack Trace:
java.lang.AssertionError: Oops: (double raws) 4631318898052956160 - 4628496337733101339 < 4442235333156365461 Calculated diff 4625071700926640586 Let's see what the JVM thinks these bits are. FloatResult:  43.5 floatTest: 27.72225134241226 Diff 15.77774865758774
	at __randomizedtesting.SeedInfo.seed([AB58247AB2E07978:AE9864CF82837D77]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.apache.solr.analytics.NoFacetTest.stddevTest(NoFacetTest.java:227)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	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 10842 lines...]
   [junit4] Suite: org.apache.solr.analytics.NoFacetTest
   [junit4]   2> 1993267 T7473 oas.SolrTestCaseJ4.initCore ####initCore
   [junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061
   [junit4]   2> 1993269 T7473 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
   [junit4]   2> 1993271 T7473 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1993271 T7473 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1993319 T7473 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1993329 T7473 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
   [junit4]   2> 1993332 T7473 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
   [junit4]   2> 1993338 T7473 oass.IndexSchema.readSchema [null] Schema name=schema-docValues
   [junit4]   2> 1993353 T7473 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1993354 T7473 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1993355 T7473 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
   [junit4]   2> 1993356 T7473 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
   [junit4]   2> 1993381 T7473 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1993381 T7473 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
   [junit4]   2> 1993383 T7473 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
   [junit4]   2> 1993427 T7473 oasc.CoreContainer.<init> New CoreContainer 3810782
   [junit4]   2> 1993430 T7473 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\]
   [junit4]   2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
   [junit4]   2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
   [junit4]   2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1993431 T7473 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 1993433 T7473 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 1993433 T7473 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 1993438 T7473 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1993438 T7473 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1993438 T7473 oasc.CoreContainer.load Host Name: 
   [junit4]   2> 1993445 T7474 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1
   [junit4]   2> 1993446 T7474 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
   [junit4]   2> 1993447 T7474 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1993447 T7474 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1993488 T7474 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
   [junit4]   2> 1993497 T7474 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-basic.xml
   [junit4]   2> 1993498 T7474 oass.IndexSchema.readSchema Reading Solr Schema from schema-analytics.xml
   [junit4]   2> 1993505 T7474 oass.IndexSchema.readSchema [collection1] Schema name=schema-docValues
   [junit4]   2> 1993519 T7474 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1993521 T7474 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1993522 T7474 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\
   [junit4]   2> 1993524 T7474 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
   [junit4]   2> 1993525 T7474 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\
   [junit4]   2> 1993525 T7474 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index/
   [junit4]   2> 1993526 T7474 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index' doesn't exist. Creating new index...
   [junit4]   2> 1993527 T7474 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index
   [junit4]   2> 1993528 T7474 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=11.67578125, floorSegmentMB=0.4619140625, forceMergeDeletesPctAllowed=2.221663527451109, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3309897509307359
   [junit4]   2> 1993531 T7474 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_1,generation=1}
   [junit4]   2> 1993532 T7474 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1993535 T7474 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1993536 T7474 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 1993537 T7474 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 1993544 T7474 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1993545 T7474 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 1993545 T7474 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 1993546 T7474 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=6.0595703125, floorSegmentMB=0.98828125, forceMergeDeletesPctAllowed=8.48780093132007, segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8091214734897925
   [junit4]   2> 1993548 T7474 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_1,generation=1}
   [junit4]   2> 1993548 T7474 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 1993548 T7474 oass.SolrIndexSearcher.<init> Opening Searcher@796684 main
   [junit4]   2> 1993550 T7474 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 1993552 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@796684 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 1993554 T7473 oas.SolrTestCaseJ4.initCore ####initCore end
   [junit4]   2> ASYNC  NEW_CORE C8568 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
   [junit4]   2> 1993559 T7473 C8568 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1993560 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {deleteByQuery=*:*} 0 4
   [junit4]   2> 1993562 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10000]} 0 1
   [junit4]   2> 1993566 T7473 C8568 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993577 T7473 C8568 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_2,generation=2}
   [junit4]   2> 1993579 T7473 C8568 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 1993581 T7473 C8568 oass.SolrIndexSearcher.<init> Opening Searcher@1b56e72 main
   [junit4]   2> 1993582 T7473 C8568 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993582 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b56e72 main{StandardDirectoryReader(segments_2:4:nrt _0(5.0):c1)}
   [junit4]   2> 1993583 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 18
   [junit4]   2> 1993586 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10001]} 0 2
   [junit4]   2> 1993589 T7473 C8568 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993594 T7473 C8568 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_3,generation=3}
   [junit4]   2> 1993594 T7473 C8568 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 1993598 T7473 C8568 oass.SolrIndexSearcher.<init> Opening Searcher@101fb53 main
   [junit4]   2> 1993598 T7473 C8568 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993598 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@101fb53 main{StandardDirectoryReader(segments_3:6:nrt _0(5.0):c1 _1(5.0):c1)}
   [junit4]   2> 1993599 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
   [junit4]   2> 1993602 T7473 C8568 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10002]} 0 1
   [junit4]   2> ASYNC  NEW_CORE C8569 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
   [junit4]   2> 1993611 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993618 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_3,generation=3}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_4,generation=4}
   [junit4]   2> 1993618 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
   [junit4]   2> 1993621 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@38f65e main
   [junit4]   2> 1993621 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993622 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@38f65e main{StandardDirectoryReader(segments_4:8:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1)}
   [junit4]   2> 1993622 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 1993626 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10003]} 0 3
   [junit4]   2> 1993628 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993632 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_4,generation=4}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_5,generation=5}
   [junit4]   2> 1993634 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
   [junit4]   2> 1993639 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@c4ba6d main
   [junit4]   2> 1993639 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993640 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c4ba6d main{StandardDirectoryReader(segments_5:10:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1)}
   [junit4]   2> 1993641 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
   [junit4]   2> 1993649 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10004]} 0 2
   [junit4]   2> 1993651 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993656 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_5,generation=5}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_6,generation=6}
   [junit4]   2> 1993658 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 6
   [junit4]   2> 1993660 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@6491aa main
   [junit4]   2> 1993661 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993661 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6491aa main{StandardDirectoryReader(segments_6:12:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1)}
   [junit4]   2> 1993662 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 1993666 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10005]} 0 1
   [junit4]   2> 1993667 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993674 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_6,generation=6}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_7,generation=7}
   [junit4]   2> 1993674 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 7
   [junit4]   2> 1993676 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@1d3781a main
   [junit4]   2> 1993677 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993677 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1d3781a main{StandardDirectoryReader(segments_7:14:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1)}
   [junit4]   2> 1993678 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 1993680 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10006]} 0 1
   [junit4]   2> 1993684 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993690 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_7,generation=7}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_8,generation=8}
   [junit4]   2> 1993690 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 8
   [junit4]   2> 1993693 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@84beac main
   [junit4]   2> 1993694 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993694 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@84beac main{StandardDirectoryReader(segments_8:16:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1)}
   [junit4]   2> 1993695 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 1993701 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10007]} 0 5
   [junit4]   2> 1993705 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993712 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_8,generation=8}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_9,generation=9}
   [junit4]   2> 1993715 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 9
   [junit4]   2> 1993718 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@1a7c243 main
   [junit4]   2> 1993719 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993719 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a7c243 main{StandardDirectoryReader(segments_9:18:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1)}
   [junit4]   2> 1993720 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 15
   [junit4]   2> 1993724 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10008]} 0 4
   [junit4]   2> 1993725 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993732 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_9,generation=9}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_a,generation=10}
   [junit4]   2> 1993733 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 10
   [junit4]   2> 1993736 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@187304f main
   [junit4]   2> 1993738 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993738 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@187304f main{StandardDirectoryReader(segments_a:20:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1)}
   [junit4]   2> 1993739 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 15
   [junit4]   2> 1993743 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[10009]} 0 4
   [junit4]   2> 1993746 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993752 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_a,generation=10}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_b,generation=11}
   [junit4]   2> 1993755 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 11
   [junit4]   2> 1993757 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@1a325dd main
   [junit4]   2> 1993758 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993760 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a325dd main{StandardDirectoryReader(segments_b:22:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1)}
   [junit4]   2> 1993760 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 16
   [junit4]   2> 1993763 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100010]} 0 2
   [junit4]   2> 1993765 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993772 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_b,generation=11}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_c,generation=12}
   [junit4]   2> 1993772 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 12
   [junit4]   2> 1993774 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@7ee6f6 main
   [junit4]   2> 1993776 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993777 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7ee6f6 main{StandardDirectoryReader(segments_c:24:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1)}
   [junit4]   2> 1993779 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
   [junit4]   2> 1993781 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100011]} 0 1
   [junit4]   2> 1993784 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993790 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_c,generation=12}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_d,generation=13}
   [junit4]   2> 1993790 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 13
   [junit4]   2> 1993793 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@12c7cd1 main
   [junit4]   2> 1993793 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993794 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12c7cd1 main{StandardDirectoryReader(segments_d:26:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1)}
   [junit4]   2> 1993795 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 1993800 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100012]} 0 5
   [junit4]   2> 1993802 T7473 C8569 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993809 T7473 C8569 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_d,generation=13}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_e,generation=14}
   [junit4]   2> 1993811 T7473 C8569 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 14
   [junit4]   2> 1993813 T7473 C8569 oass.SolrIndexSearcher.<init> Opening Searcher@9b56be main
   [junit4]   2> 1993814 T7473 C8569 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993814 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@9b56be main{StandardDirectoryReader(segments_e:28:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1)}
   [junit4]   2> 1993815 T7473 C8569 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
   [junit4]   2> ASYNC  NEW_CORE C8570 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
   [junit4]   2> 1993823 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100013]} 0 1
   [junit4]   2> 1993827 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993831 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_e,generation=14}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_f,generation=15}
   [junit4]   2> 1993833 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 15
   [junit4]   2> 1993834 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1b33b55 main
   [junit4]   2> 1993836 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993837 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b33b55 main{StandardDirectoryReader(segments_f:30:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1)}
   [junit4]   2> 1993837 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
   [junit4]   2> 1993839 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100014]} 0 0
   [junit4]   2> 1993842 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993848 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_f,generation=15}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_g,generation=16}
   [junit4]   2> 1993849 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 16
   [junit4]   2> 1993852 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1651603 main
   [junit4]   2> 1993853 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993853 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1651603 main{StandardDirectoryReader(segments_g:32:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1)}
   [junit4]   2> 1993855 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
   [junit4]   2> 1993858 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100015]} 0 1
   [junit4]   2> 1993859 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993865 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_g,generation=16}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_h,generation=17}
   [junit4]   2> 1993866 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 17
   [junit4]   2> 1993868 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@b7058e main
   [junit4]   2> 1993868 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993869 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b7058e main{StandardDirectoryReader(segments_h:34:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1)}
   [junit4]   2> 1993870 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 1993874 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100016]} 0 2
   [junit4]   2> 1993876 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993881 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_h,generation=17}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_i,generation=18}
   [junit4]   2> 1993884 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 18
   [junit4]   2> 1993885 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1b64ad1 main
   [junit4]   2> 1993886 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993886 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b64ad1 main{StandardDirectoryReader(segments_i:36:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1)}
   [junit4]   2> 1993888 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
   [junit4]   2> 1993890 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100017]} 0 1
   [junit4]   2> 1993893 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993898 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_i,generation=18}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_j,generation=19}
   [junit4]   2> 1993899 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 19
   [junit4]   2> 1993900 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@2629e9 main
   [junit4]   2> 1993903 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993903 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2629e9 main{StandardDirectoryReader(segments_j:38:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1)}
   [junit4]   2> 1993904 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 1993906 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100018]} 0 1
   [junit4]   2> 1993908 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993915 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_j,generation=19}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_k,generation=20}
   [junit4]   2> 1993915 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 20
   [junit4]   2> 1993917 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1ede14c main
   [junit4]   2> 1993918 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993918 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ede14c main{StandardDirectoryReader(segments_k:40:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1)}
   [junit4]   2> 1993919 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
   [junit4]   2> 1993923 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100019]} 0 1
   [junit4]   2> 1993924 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993930 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_k,generation=20}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_l,generation=21}
   [junit4]   2> 1993932 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 21
   [junit4]   2> 1993934 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1bfb387 main
   [junit4]   2> 1993935 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993935 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1bfb387 main{StandardDirectoryReader(segments_l:42:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1)}
   [junit4]   2> 1993936 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
   [junit4]   2> 1993938 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100020]} 0 1
   [junit4]   2> 1993941 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993947 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_l,generation=21}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_m,generation=22}
   [junit4]   2> 1993947 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 22
   [junit4]   2> 1993949 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1deb182 main
   [junit4]   2> 1993949 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993952 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1deb182 main{StandardDirectoryReader(segments_m:44:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1)}
   [junit4]   2> 1993952 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 1993955 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100021]} 0 1
   [junit4]   2> 1993957 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993962 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_m,generation=22}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_n,generation=23}
   [junit4]   2> 1993962 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 23
   [junit4]   2> 1993965 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@13d8dda main
   [junit4]   2> 1993965 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993966 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13d8dda main{StandardDirectoryReader(segments_n:46:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1)}
   [junit4]   2> 1993967 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 1993968 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100022]} 0 1
   [junit4]   2> 1993972 T7473 C8570 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1993977 T7473 C8570 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_n,generation=23}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_o,generation=24}
   [junit4]   2> 1993979 T7473 C8570 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 24
   [junit4]   2> 1993980 T7473 C8570 oass.SolrIndexSearcher.<init> Opening Searcher@1c622d2 main
   [junit4]   2> 1993982 T7473 C8570 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1993983 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1c622d2 main{StandardDirectoryReader(segments_o:48:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1)}
   [junit4]   2> 1993984 T7473 C8570 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
   [junit4]   2> ASYNC  NEW_CORE C8571 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
   [junit4]   2> 1993995 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100023]} 0 10
   [junit4]   2> 1993998 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994002 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_o,generation=24}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_p,generation=25}
   [junit4]   2> 1994004 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 25
   [junit4]   2> 1994005 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@c9033e main
   [junit4]   2> 1994005 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994008 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c9033e main{StandardDirectoryReader(segments_p:50:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1)}
   [junit4]   2> 1994009 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 11
   [junit4]   2> 1994011 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100024]} 0 1
   [junit4]   2> 1994013 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994023 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_p,generation=25}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_q,generation=26}
   [junit4]   2> 1994023 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 26
   [junit4]   2> 1994028 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@14e312b main
   [junit4]   2> 1994028 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994028 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@14e312b main{StandardDirectoryReader(segments_q:52:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1)}
   [junit4]   2> 1994029 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 17
   [junit4]   2> 1994030 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100025]} 0 1
   [junit4]   2> 1994033 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994038 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_q,generation=26}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_r,generation=27}
   [junit4]   2> 1994039 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 27
   [junit4]   2> 1994041 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@146b134 main
   [junit4]   2> 1994041 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994042 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@146b134 main{StandardDirectoryReader(segments_r:54:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1)}
   [junit4]   2> 1994043 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 10
   [junit4]   2> 1994048 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100026]} 0 2
   [junit4]   2> 1994050 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994059 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_r,generation=27}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_s,generation=28}
   [junit4]   2> 1994060 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 28
   [junit4]   2> 1994062 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@ddda20 main
   [junit4]   2> 1994062 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994063 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ddda20 main{StandardDirectoryReader(segments_s:56:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1)}
   [junit4]   2> 1994063 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
   [junit4]   2> 1994067 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100027]} 0 2
   [junit4]   2> 1994070 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994079 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_s,generation=28}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_t,generation=29}
   [junit4]   2> 1994079 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 29
   [junit4]   2> 1994079 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@15bb2ba main
   [junit4]   2> 1994082 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994082 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15bb2ba main{StandardDirectoryReader(segments_t:58:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1)}
   [junit4]   2> 1994084 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 16
   [junit4]   2> 1994085 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100028]} 0 1
   [junit4]   2> 1994088 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994094 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_t,generation=29}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_u,generation=30}
   [junit4]   2> 1994095 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 30
   [junit4]   2> 1994096 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@1737862 main
   [junit4]   2> 1994098 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1737862 main{StandardDirectoryReader(segments_u:60:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1)}
   [junit4]   2> 1994099 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994099 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
   [junit4]   2> 1994101 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100029]} 0 0
   [junit4]   2> 1994105 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994113 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_u,generation=30}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_v,generation=31}
   [junit4]   2> 1994114 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 31
   [junit4]   2> 1994115 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@1380011 main
   [junit4]   2> 1994116 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1380011 main{StandardDirectoryReader(segments_v:62:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1)}
   [junit4]   2> 1994117 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994117 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
   [junit4]   2> 1994120 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100030]} 0 2
   [junit4]   2> 1994125 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994137 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_v,generation=31}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_w,generation=32}
   [junit4]   2> 1994138 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 32
   [junit4]   2> 1994143 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@51e92d main
   [junit4]   2> 1994144 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994144 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@51e92d main{StandardDirectoryReader(segments_w:64:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1)}
   [junit4]   2> 1994145 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 22
   [junit4]   2> 1994151 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100031]} 0 3
   [junit4]   2> 1994153 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994164 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_w,generation=32}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_x,generation=33}
   [junit4]   2> 1994165 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 33
   [junit4]   2> 1994168 T7473 C8571 oass.SolrIndexSearcher.<init> Opening Searcher@d4fac3 main
   [junit4]   2> 1994170 T7473 C8571 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994170 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d4fac3 main{StandardDirectoryReader(segments_x:66:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1)}
   [junit4]   2> 1994171 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 18
   [junit4]   2> 1994173 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100032]} 0 1
   [junit4]   2> 1994176 T7473 C8571 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100033]} 0 1
   [junit4]   2> 1994177 T7473 C8571 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994186 T7473 C8571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_x,generation=33}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_y,generation=34}
   [junit4]   2> 1994186 T7473 C8571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 34
   [junit4]   2> ASYNC  NEW_CORE C8572 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
   [junit4]   2> 1994196 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@90b6e9 main
   [junit4]   2> 1994196 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994199 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@90b6e9 main{StandardDirectoryReader(segments_y:68:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2)}
   [junit4]   2> 1994200 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 23
   [junit4]   2> 1994202 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100034]} 0 2
   [junit4]   2> 1994204 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994210 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_y,generation=34}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_z,generation=35}
   [junit4]   2> 1994212 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 35
   [junit4]   2> 1994212 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@3818db main
   [junit4]   2> 1994213 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994215 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3818db main{StandardDirectoryReader(segments_z:70:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1)}
   [junit4]   2> 1994215 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
   [junit4]   2> 1994220 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100035]} 0 2
   [junit4]   2> 1994222 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994234 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_z,generation=35}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_10,generation=36}
   [junit4]   2> 1994235 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 36
   [junit4]   2> 1994238 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@161ee23 main
   [junit4]   2> 1994238 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994239 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@161ee23 main{StandardDirectoryReader(segments_10:72:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1)}
   [junit4]   2> 1994240 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 18
   [junit4]   2> 1994243 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100036]} 0 1
   [junit4]   2> 1994246 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994252 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_10,generation=36}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_11,generation=37}
   [junit4]   2> 1994253 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 37
   [junit4]   2> 1994256 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@729f2e main
   [junit4]   2> 1994256 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994257 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@729f2e main{StandardDirectoryReader(segments_11:74:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1)}
   [junit4]   2> 1994258 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
   [junit4]   2> 1994260 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100037]} 0 1
   [junit4]   2> 1994263 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994275 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_11,generation=37}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_12,generation=38}
   [junit4]   2> 1994276 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 38
   [junit4]   2> 1994279 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@12d462c main
   [junit4]   2> 1994281 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994281 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@12d462c main{StandardDirectoryReader(segments_12:76:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1)}
   [junit4]   2> 1994282 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 20
   [junit4]   2> 1994286 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100038]} 0 1
   [junit4]   2> 1994289 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994296 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_12,generation=38}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_13,generation=39}
   [junit4]   2> 1994297 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 39
   [junit4]   2> 1994298 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@f95afc main
   [junit4]   2> 1994300 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994300 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f95afc main{StandardDirectoryReader(segments_13:78:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1)}
   [junit4]   2> 1994301 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
   [junit4]   2> 1994304 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100039]} 0 2
   [junit4]   2> 1994306 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994315 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_13,generation=39}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_14,generation=40}
   [junit4]   2> 1994315 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 40
   [junit4]   2> 1994317 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@15f6252 main
   [junit4]   2> 1994318 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994318 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15f6252 main{StandardDirectoryReader(segments_14:80:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1)}
   [junit4]   2> 1994319 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
   [junit4]   2> 1994326 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100040]} 0 5
   [junit4]   2> 1994332 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994338 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_14,generation=40}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_15,generation=41}
   [junit4]   2> 1994338 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 41
   [junit4]   2> 1994342 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@2c6f20 main
   [junit4]   2> 1994343 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994344 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2c6f20 main{StandardDirectoryReader(segments_15:82:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1 _13(5.0):c1)}
   [junit4]   2> 1994344 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
   [junit4]   2> 1994347 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100041]} 0 2
   [junit4]   2> 1994349 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994357 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_15,generation=41}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_16,generation=42}
   [junit4]   2> 1994357 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 42
   [junit4]   2> 1994359 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@f38125 main
   [junit4]   2> 1994359 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994361 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f38125 main{StandardDirectoryReader(segments_16:84:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1 _13(5.0):c1 _14(5.0):c1)}
   [junit4]   2> 1994361 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
   [junit4]   2> 1994363 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100042]} 0 0
   [junit4]   2> 1994365 T7473 C8572 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994374 T7473 C8572 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_16,generation=42}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_17,generation=43}
   [junit4]   2> 1994374 T7473 C8572 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 43
   [junit4]   2> 1994376 T7473 C8572 oass.SolrIndexSearcher.<init> Opening Searcher@19956ad main
   [junit4]   2> 1994376 T7473 C8572 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994377 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19956ad main{StandardDirectoryReader(segments_17:86:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1 _13(5.0):c1 _14(5.0):c1 _15(5.0):c1)}
   [junit4]   2> 1994378 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 14
   [junit4]   2> 1994381 T7473 C8572 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100043]} 0 3
   [junit4]   2> ASYNC  NEW_CORE C8573 name=collection1 org.apache.solr.core.SolrCore@12f8bc4
   [junit4]   2> 1994393 T7473 C8573 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994401 T7473 C8573 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_17,generation=43}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_18,generation=44}
   [junit4]   2> 1994401 T7473 C8573 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 44
   [junit4]   2> 1994403 T7473 C8573 oass.SolrIndexSearcher.<init> Opening Searcher@11da4da main
   [junit4]   2> 1994403 T7473 C8573 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994406 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11da4da main{StandardDirectoryReader(segments_18:88:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1 _13(5.0):c1 _14(5.0):c1 _15(5.0):c1 _16(5.0):c1)}
   [junit4]   2> 1994406 T7473 C8573 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 13
   [junit4]   2> 1994410 T7473 C8573 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100044]} 0 1
   [junit4]   2> 1994411 T7473 C8573 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994419 T7473 C8573 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_18,generation=44}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@18614f8 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f381ef),segFN=segments_19,generation=45}
   [junit4]   2> 1994420 T7473 C8573 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 45
   [junit4]   2> 1994422 T7473 C8573 oass.SolrIndexSearcher.<init> Opening Searcher@1e3ac83 main
   [junit4]   2> 1994422 T7473 C8573 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 1994423 T7475 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e3ac83 main{StandardDirectoryReader(segments_19:90:nrt _0(5.0):c1 _1(5.0):c1 _2(5.0):c1 _3(5.0):c1 _4(5.0):c1 _5(5.0):c1 _6(5.0):c1 _7(5.0):c1 _8(5.0):c1 _9(5.0):c1 _a(5.0):c1 _b(5.0):c1 _c(5.0):c1 _d(5.0):c1 _e(5.0):c1 _f(5.0):c1 _g(5.0):c1 _h(5.0):c1 _i(5.0):c1 _j(5.0):c1 _k(5.0):c1 _l(5.0):c1 _m(5.0):c1 _n(5.0):c1 _o(5.0):c1 _p(5.0):c1 _q(5.0):c1 _r(5.0):c1 _s(5.0):c1 _t(5.0):c1 _u(5.0):c1 _v(5.0):c1 _w(5.0):c2 _x(5.0):c1 _y(5.0):c1 _z(5.0):c1 _10(5.0):c1 _11(5.0):c1 _12(5.0):c1 _13(5.0):c1 _14(5.0):c1 _15(5.0):c1 _16(5.0):c1 _17(5.0):c1)}
   [junit4]   2> 1994423 T7473 C8573 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 12
   [junit4]   2> 1994425 T7473 C8573 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[100045]} 0 1
   [junit4]   2> 1994428 T7473 C8573 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1994443 T7473 C8573 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.

[...truncated too long message...]

workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index;done=false>>]
   [junit4]   2> 1995662 T7473 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-NoFacetTest-1385243915061\index
   [junit4]   2> NOTE: test params are: codec=Lucene46: {double_d=MockFixedIntBlock(blockSize=1705), float_f=MockVariableIntBlock(baseBlockSize=18), int_i=MockVariableIntBlock(baseBlockSize=18), long_l=PostingsFormat(name=MockSep), long_ld=PostingsFormat(name=MockSep), date_dtd=PostingsFormat(name=MockSep), string_sd=MockVariableIntBlock(baseBlockSize=18), int_id=MockVariableIntBlock(baseBlockSize=18), id=PostingsFormat(name=MockSep), double_dd=MockFixedIntBlock(blockSize=1705), float_fd=MockVariableIntBlock(baseBlockSize=18)}, docValues:{date_dtd=DocValuesFormat(name=Disk), doubledv=DocValuesFormat(name=Disk), string_sd=DocValuesFormat(name=Memory), float_fd=DocValuesFormat(name=Memory), datedv=DocValuesFormat(name=Lucene45), long_ld=DocValuesFormat(name=Disk), longdv=DocValuesFormat(name=SimpleText), intdv=DocValuesFormat(name=Memory), int_id=DocValuesFormat(name=Memory), stringdvm=DocValuesFormat(name=SimpleText), double_dd=DocValuesFormat(name=SimpleText), stringdv=DocValuesFormat(name=Disk), floatdv=DocValuesFormat(name=SimpleText)}, sim=DefaultSimilarity, locale=en_SG, timezone=America/St_Thomas
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0-ea (32-bit)/cpus=2,threads=1,free=115492032,total=276701184
   [junit4]   2> NOTE: All tests run in this JVM: [TestSolrCoreProperties, SchemaVersionSpecificBehaviorTest, TestCopyFieldCollectionResource, TestSweetSpotSimilarityFactory, TestCollationField, DocValuesMultiTest, TestFunctionQuery, AnalysisAfterCoreReloadTest, SpellPossibilityIteratorTest, ZkSolrClientTest, CoreMergeIndexesAdminHandlerTest, TestBM25SimilarityFactory, TestModifyConfFiles, TestLMJelinekMercerSimilarityFactory, TestFieldResource, HdfsUnloadDistributedZkTest, TestRealTimeGet, SearchHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, MultiTermTest, OutputWriterTest, TestSolrXmlPersistor, PluginInfoTest, StressHdfsTest, EnumFieldTest, BasicFunctionalityTest, LukeRequestHandlerTest, FullSolrCloudDistribCmdsTest, TestPartialUpdateDeduplication, DeleteShardTest, TestCSVLoader, SuggesterFSTTest, DeleteInactiveReplicaTest, HighlighterTest, TestDistribDocBasedVersion, TestFileDictionaryLookup, TestNonNRTOpen, SpellCheckComponentTest, PeerSyncTest, TestStressVersions, ShardRoutingTest, TestSolrIndexConfig, TestCodecSupport, TestDFRSimilarityFactory, TestValueSourceCache, TestRecovery, SimpleFacetsTest, TestGroupingSearch, CurrencyFieldXmlFileTest, TestSolrQueryParserDefaultOperatorResource, SolrIndexSplitterTest, TestRandomDVFaceting, TestDefaultSimilarityFactory, PolyFieldTest, TestLazyCores, TestAnalyzeInfixSuggestions, FieldFacetTest, PingRequestHandlerTest, TestDefaultSearchFieldResource, LegacyHTMLStripCharFilterTest, TestConfig, TestInfoStreamLogging, HardAutoCommitTest, BadCopyFieldTest, TestSolrDeletionPolicy2, JSONWriterTest, TestNumberUtils, IndexSchemaRuntimeFieldTest, TestJmxMonitoredMap, TestSolrQueryParser, OverseerCollectionProcessorTest, JsonLoaderTest, TestFastLRUCache, ZkControllerTest, DirectUpdateHandlerOptimizeTest, DebugComponentTest, BlockDirectoryTest, SolrTestCaseJ4Test, TestSimpleQParserPlugin, TestWriterPerf, TestNRTOpen, TestPhraseSuggestions, TermVectorComponentDistributedTest, DateFieldTest, ResourceLoaderTest, HdfsCollectionsAPIDistributedZkTest, TestSolrXml, TestHashPartitioner, TriLevelCompositeIdRoutingTest, TestReload, AssignTest, NoCacheHeaderTest, NumericFieldsTest, QueryResultKeyTest, TestComponentsName, WordBreakSolrSpellCheckerTest, UpdateRequestProcessorFactoryTest, TestDynamicFieldResource, FastVectorHighlighterTest, TestAddFieldRealTimeGet, UUIDFieldTest, QueryElevationComponentTest, HighlighterConfigTest, PrimitiveFieldTypeTest, SolrCoreCheckLockOnStartupTest, SynonymTokenizerTest, LoggingHandlerTest, CacheHeaderTest, CachingDirectoryFactoryTest, TestSort, ExpressionTest, HdfsRecoveryZkTest, PreAnalyzedUpdateProcessorTest, TestXIncludeConfig, SoftAutoCommitTest, FieldAnalysisRequestHandlerTest, PrimUtilsTest, PathHierarchyTokenizerFactoryTest, TestUniqueKeyFieldResource, ScriptEngineTest, TestPHPSerializedResponseWriter, EchoParamsTest, SuggesterTSTTest, SolrRequestParserTest, TestSolrXmlPersistence, HdfsBasicDistributedZk2Test, ExternalFileFieldSortTest, RequestHandlersTest, DisMaxRequestHandlerTest, CoreAdminHandlerTest, ShardRoutingCustomTest, HdfsLockFactoryTest, TestManagedSchemaFieldResource, TestSurroundQueryParser, IndexSchemaTest, HdfsBasicDistributedZkTest, FileUtilsTest, BasicZkTest, TestFuzzyAnalyzedSuggestions, SliceStateTest, TestClassNameShortening, BJQParserTest, SimplePostToolTest, TestUtils, TestSchemaNameResource, SampleTest, DateMathParserTest, TestDocSet, TestStressUserVersions, TestBinaryField, AddBlockUpdateTest, CollectionsAPIDistributedZkTest, TestLMDirichletSimilarityFactory, TestReversedWildcardFilterFactory, SpellCheckCollatorTest, ReturnFieldsTest, SpatialFilterTest, StatsComponentTest, DistributedTermsComponentTest, DistributedDebugComponentTest, TestPerFieldSimilarity, TestFastWriter, ConvertedLegacyTest, DirectUpdateHandlerTest, TestSearchPerf, TestBinaryResponseWriter, TestDynamicFieldCollectionResource, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, RecoveryZkTest, TestReplicationHandler, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, TestZkChroot, ZkCLITest, SliceStateUpdateTest, TestDistributedSearch, AutoCommitTest, TestDistributedGrouping, TestFaceting, DistributedSpellCheckComponentTest, TestStressReorder, TestJoin, TestMultiCoreConfBootstrap, TestRangeQuery, TestCoreContainer, TestSolr4Spatial, SolrCoreTest, SolrCmdDistributorTest, BadIndexSchemaTest, TestFiltering, TestBadConfig, TestIndexSearcher, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, TestFoldingMultitermQuery, DocValuesTest, SuggesterTest, TestStressLucene, TestTrie, SuggesterWFSTTest, TestPseudoReturnFields, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, QueryEqualityTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, TestRemoteStreaming, DistanceFunctionTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, TestQueryUtils, StandardRequestHandlerTest, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes, TestOmitPositions, FileBasedSpellCheckerTest, TermsComponentTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, TermVectorComponentTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, TestArbitraryIndexDir, RegexBoostProcessorTest, SolrPluginUtilsTest, TestJmxIntegration, TestCSVResponseWriter, MBeansHandlerTest, QueryParsingTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, BinaryUpdateRequestHandlerTest, CSVRequestHandlerTest, CoreContainerCoreInitFailuresTest, TestLFUCache, SOLR749Test, TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, TestMergePolicyConfig, MinimalSchemaTest, TestElisionMultitermQuery, TestPostingsSolrHighlighter, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestCharFilters, TestIBSimilarityFactory, TimeZoneUtilsTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, ChaosMonkeyNothingIsSafeTest, TestSolrXMLSerializer, PreAnalyzedFieldTest, TestSystemIdResolver, TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, ClusterStateTest, TestSolrJ, TestLRUCache, TestDocumentBuilder, ZkNodePropsTest, SystemInfoHandlerTest, CircularListTest, TestRTGBase, AbstractAnalyticsStatsTest, NoFacetTest]
   [junit4] Completed in 2.44s, 12 tests, 1 failure <<< FAILURES!

[...truncated 143 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:420: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:400: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:492: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1272: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:908: There were test failures: 353 suites, 1558 tests, 1 failure, 43 ignored (11 assumptions)

Total time: 67 minutes 17 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b116 -client -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure