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/10/30 09:12:34 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_45) - Build #
3406 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3406/
Java: 32bit/jdk1.7.0_45 -server -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestBackup
Error Message:
Backup success not detected:<?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><lst name="details"><str name="indexSize">21.05 KB</str><str name="indexPath">C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119958062\master\collection1\data\index/</str><arr name="commits"><lst><long name="indexVersion">1383119960125</long><long name="generation">2</long><arr name="filelist"><str>_0.dnvd</str><str>_0.dnvm</str><str>_0.doc</str><str>_0.fdt</str><str>_0.fdx</str><str>_0.fnm</str><str>_0.si</str><str>_0.tim</str><str>_0.tip</str><str>segments_2</str></arr></lst></arr><str name="isMaster">true</str><str name="isSlave">false</str><long name="indexVersion">1383119960125</long><long name="generation">2</long><lst name="master"><str name="confFiles">schema-replication2.xml:schema.xml</str><arr name="replicateAfter"><str>commit</str></arr><str name="replicationEnabled">true</str><long name="replicableVersion">1383119960125</long><long name="replicableGeneration">2</long></lst><lst name="backup"><str name="startTime">Wed Oct 30 03:59:20 EDT 2013</str><int name="fileCount">10</int><str name="status">success</str><str name="snapshotCompletedAt">Wed Oct 30 03:59:20 EDT 2013</str></lst></lst><str name="WARNING">This response format is experimental. It is likely to change in the future.</str> </response>
Stack Trace:
java.lang.AssertionError: Backup success not detected:<?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">1</int></lst><lst name="details"><str name="indexSize">21.05 KB</str><str name="indexPath">C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119958062\master\collection1\data\index/</str><arr name="commits"><lst><long name="indexVersion">1383119960125</long><long name="generation">2</long><arr name="filelist"><str>_0.dnvd</str><str>_0.dnvm</str><str>_0.doc</str><str>_0.fdt</str><str>_0.fdx</str><str>_0.fnm</str><str>_0.si</str><str>_0.tim</str><str>_0.tip</str><str>segments_2</str></arr></lst></arr><str name="isMaster">true</str><str name="isSlave">false</str><long name="indexVersion">1383119960125</long><long name="generation">2</long><lst name="master"><str name="confFiles">schema-replication2.xml:schema.xml</str><arr name="replicateAfter"><str>commit</str></arr><str name="replicationEnabled">true</str><long name="replicableVersion">1383119960125</long><long name="replicableGeneration">2</long></lst><lst name="backup"><str name="startTime">Wed Oct 30 03:59:20 EDT 2013</str><int name="fileCount">10</int><str name="status">success</str><str name="snapshotCompletedAt">Wed Oct 30 03:59:20 EDT 2013</str></lst></lst><str name="WARNING">This response format is experimental. It is likely to change in the future.</str>
</response>
at __randomizedtesting.SeedInfo.seed([15CC661DB9423446:544746789EFCC709]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.doTestBackup(TestReplicationHandler.java:1403)
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:606)
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 10295 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 2372358 T6130 oas.SolrTestCaseJ4.setUp ###Starting testNoWriter
[junit4] 2> 2372395 T6130 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2372404 T6130 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53921
[junit4] 2> 2372405 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2372406 T6130 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2372406 T6130 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master
[junit4] 2> 2372407 T6130 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\'
[junit4] 2> 2372464 T6130 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\solr.xml
[junit4] 2> 2372606 T6130 oasc.CoreContainer.<init> New CoreContainer 22200594
[junit4] 2> 2372607 T6130 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\]
[junit4] 2> 2372610 T6130 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2372611 T6130 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2372611 T6130 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2372612 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2372612 T6130 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2372613 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2372613 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2372614 T6130 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2372614 T6130 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2372623 T6130 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2372624 T6130 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2372639 T6140 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1
[junit4] 2> 2372639 T6140 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\'
[junit4] 2> 2372740 T6140 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2372819 T6140 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2372821 T6140 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2372832 T6140 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2372871 T6140 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2372874 T6140 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2372876 T6140 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\
[junit4] 2> 2372876 T6140 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2372877 T6140 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\
[junit4] 2> 2372877 T6140 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index/
[junit4] 2> 2372877 T6140 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2372878 T6140 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index
[junit4] 2> 2372879 T6140 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=11.888671875, floorSegmentMB=2.19140625, forceMergeDeletesPctAllowed=26.83044167394666, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2372880 T6140 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@cafc7f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@625a85),segFN=segments_1,generation=1}
[junit4] 2> 2372880 T6140 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2372882 T6140 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2372883 T6140 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2372883 T6140 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2372883 T6140 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2372883 T6140 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2372883 T6140 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2372885 T6140 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2372902 T6140 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2372909 T6140 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2372909 T6140 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2372910 T6140 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=88.3447265625, floorSegmentMB=1.1123046875, forceMergeDeletesPctAllowed=5.603673704399746, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2372911 T6140 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@cafc7f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@625a85),segFN=segments_1,generation=1}
[junit4] 2> 2372911 T6140 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2372912 T6140 oass.SolrIndexSearcher.<init> Opening Searcher@13d7e87 main
[junit4] 2> 2372912 T6140 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2372913 T6140 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2372913 T6141 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13d7e87 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2372913 T6140 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2372915 T6130 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2372916 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2372943 T6130 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2372952 T6130 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53924
[junit4] 2> 2372954 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2372954 T6130 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2372955 T6130 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave
[junit4] 2> 2372955 T6130 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\'
[junit4] 2> 2372984 T6130 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\solr.xml
[junit4] 2> 2373095 T6130 oasc.CoreContainer.<init> New CoreContainer 8527625
[junit4] 2> 2373096 T6130 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\]
[junit4] 2> 2373098 T6130 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2373099 T6130 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2373100 T6130 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2373100 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2373100 T6130 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2373101 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2373101 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2373102 T6130 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2373102 T6130 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2373104 T6130 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2373105 T6130 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2373117 T6152 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1
[junit4] 2> 2373117 T6152 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\'
[junit4] 2> 2373221 T6152 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2373326 T6152 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2373326 T6152 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2373340 T6152 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2373372 T6152 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2373376 T6152 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2373376 T6152 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\
[junit4] 2> 2373376 T6152 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2373377 T6152 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\
[junit4] 2> 2373377 T6152 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index/
[junit4] 2> 2373377 T6152 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2373379 T6152 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index
[junit4] 2> 2373380 T6152 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=11.888671875, floorSegmentMB=2.19140625, forceMergeDeletesPctAllowed=26.83044167394666, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2373380 T6152 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1b4c9fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c6717e),segFN=segments_1,generation=1}
[junit4] 2> 2373380 T6152 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2373383 T6152 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2373383 T6152 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2373384 T6152 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2373384 T6152 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2373384 T6152 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2373385 T6152 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2373385 T6152 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2373389 T6152 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2373396 T6152 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2373396 T6152 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2373397 T6152 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=88.3447265625, floorSegmentMB=1.1123046875, forceMergeDeletesPctAllowed=5.603673704399746, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2373398 T6152 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1b4c9fa lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c6717e),segFN=segments_1,generation=1}
[junit4] 2> 2373398 T6152 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2373399 T6152 oass.SolrIndexSearcher.<init> Opening Searcher@c93043 main
[junit4] 2> 2373400 T6152 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2373400 T6152 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2373400 T6153 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c93043 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2373401 T6130 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2373402 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2373418 T6130 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=8527625
[junit4] 2> 2373420 T6130 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@12d64b4
[junit4] 2> 2373423 T6130 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2373423 T6130 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2373423 T6130 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2373423 T6130 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2373426 T6130 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2373426 T6130 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2373426 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\;done=false>>]
[junit4] 2> 2373426 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\
[junit4] 2> 2373428 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index;done=false>>]
[junit4] 2> 2373428 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index
[junit4] 2> 2373430 T6130 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2373498 T6130 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=22200594
[junit4] 2> 2373500 T6130 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@dba9da
[junit4] 2> 2373500 T6130 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2373500 T6130 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2373500 T6130 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2373502 T6130 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2373505 T6130 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2373506 T6130 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2373506 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\;done=false>>]
[junit4] 2> 2373506 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\
[junit4] 2> 2373506 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index;done=false>>]
[junit4] 2> 2373506 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index
[junit4] 2> 2373509 T6130 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2373563 T6130 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2373572 T6130 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53924
[junit4] 2> 2373574 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2373574 T6130 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2373575 T6130 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave
[junit4] 2> 2373575 T6130 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\'
[junit4] 2> 2373600 T6130 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\solr.xml
[junit4] 2> 2373675 T6130 oasc.CoreContainer.<init> New CoreContainer 4506873
[junit4] 2> 2373676 T6130 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\]
[junit4] 2> 2373679 T6130 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2373679 T6130 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2373679 T6130 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2373680 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2373680 T6130 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2373680 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2373680 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2373680 T6130 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2373680 T6130 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2373684 T6130 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2373684 T6130 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2373695 T6165 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1
[junit4] 2> 2373695 T6165 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\'
[junit4] 2> 2373798 T6165 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2373866 T6165 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2373866 T6165 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2373874 T6165 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2373897 T6165 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2373900 T6165 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 2373900 T6165 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\
[junit4] 2> 2373900 T6165 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2373900 T6165 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data
[junit4] 2> 2373900 T6165 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index/
[junit4] 2> 2373900 T6165 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2373900 T6165 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index
[junit4] 2> 2373902 T6165 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2373909 T6165 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6c5e4c; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 2373909 T6165 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2373911 T6165 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2373911 T6165 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2373912 T6165 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2373912 T6165 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2373912 T6165 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2373912 T6165 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2373913 T6165 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2373923 T6165 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2373931 T6165 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2373931 T6165 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2373934 T6165 oass.SolrIndexSearcher.<init> Opening Searcher@1452cce main
[junit4] 2> 2373935 T6165 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2373935 T6165 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2373935 T6166 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1452cce main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2373936 T6130 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2373936 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2373937 T6130 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2373944 T6130 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53921
[junit4] 2> 2373946 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2373946 T6130 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2373947 T6130 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master
[junit4] 2> 2373947 T6130 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\'
[junit4] 2> 2373985 T6130 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\solr.xml
[junit4] 2> 2374089 T6130 oasc.CoreContainer.<init> New CoreContainer 27017959
[junit4] 2> 2374089 T6130 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\]
[junit4] 2> 2374092 T6130 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2374092 T6130 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2374092 T6130 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2374092 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2374092 T6130 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2374092 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2374094 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2374094 T6130 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2374094 T6130 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2374099 T6130 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2374099 T6130 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2374108 T6178 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1
[junit4] 2> 2374109 T6178 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\'
[junit4] 2> 2374176 T6178 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2374248 T6178 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2374249 T6178 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2374257 T6178 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2374283 T6178 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2374285 T6178 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 2374286 T6178 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\
[junit4] 2> 2374288 T6178 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2374288 T6178 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data
[junit4] 2> 2374289 T6178 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index/
[junit4] 2> 2374290 T6178 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2374290 T6178 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index
[junit4] 2> 2374290 T6178 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2374298 T6178 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@28c21b; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 2374298 T6178 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2374299 T6178 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2374299 T6178 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2374299 T6178 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2374300 T6178 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2374300 T6178 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2374300 T6178 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2374300 T6178 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2374313 T6178 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2374322 T6178 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2374322 T6178 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2374324 T6178 oass.SolrIndexSearcher.<init> Opening Searcher@cf01b1 main
[junit4] 2> 2374325 T6178 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2374325 T6178 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2374325 T6178 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2374325 T6179 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@cf01b1 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2374326 T6130 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2374327 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C1597 name=collection1 org.apache.solr.core.SolrCore@19b38dc
[junit4] 2> 2374343 T6160 C1597 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2374344 T6160 C1597 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6c5e4c; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> 2374347 T6160 C1597 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2374348 T6160 C1597 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123456]} 0 10
[junit4] 2> 2374358 T6161 C1597 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2374388 T6161 C1597 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6c5e4c; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=NRTCachingDirectory(org.apache.lucene.store.SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6c5e4c; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2}
[junit4] 2> 2374388 T6161 C1597 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2374396 T6161 C1597 oass.SolrIndexSearcher.<init> Opening Searcher@1069fe2 main
[junit4] 2> 2374396 T6161 C1597 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2374399 T6166 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1069fe2 main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4] 2> 2374400 T6161 C1597 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 48
[junit4] 2> 2374425 T6130 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=4506873
[junit4] 2> 2374427 T6130 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@19b38dc
[junit4] 2> 2374430 T6130 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2374432 T6130 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2374432 T6130 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2374432 T6130 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2374435 T6130 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2374435 T6130 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2374435 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index;done=false>>]
[junit4] 2> 2374435 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index
[junit4] 2> 2374435 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data;done=false>>]
[junit4] 2> 2374435 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data
[junit4] 2> 2374437 T6130 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2374496 T6130 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2374503 T6130 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53924
[junit4] 2> 2374505 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2374506 T6130 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2374506 T6130 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave
[junit4] 2> 2374508 T6130 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\'
[junit4] 2> 2374558 T6130 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\solr.xml
[junit4] 2> 2374693 T6130 oasc.CoreContainer.<init> New CoreContainer 6471830
[junit4] 2> 2374693 T6130 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\]
[junit4] 2> 2374697 T6130 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2374697 T6130 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2374697 T6130 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2374697 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2374697 T6130 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2374697 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2374697 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2374697 T6130 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2374697 T6130 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2374700 T6130 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2374702 T6130 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2374711 T6190 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1
[junit4] 2> 2374711 T6190 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\'
[junit4] 2> 2374780 T6190 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2374849 T6190 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2374850 T6190 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2374858 T6190 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2374886 T6190 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2374888 T6190 oasc.SolrCore.initDirectoryFactory solr.NRTCachingDirectoryFactory
[junit4] 2> 2374889 T6190 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\
[junit4] 2> 2374889 T6190 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2374889 T6190 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data
[junit4] 2> 2374890 T6190 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index/
[junit4] 2> 2374891 T6190 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2374891 T6190 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2374891 T6190 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2374892 T6190 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2374892 T6190 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2374892 T6190 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2374892 T6190 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2374903 T6190 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2374908 T6190 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2374908 T6190 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2374909 T6190 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index
[junit4] 2> 2374912 T6190 oass.SolrIndexSearcher.<init> Opening Searcher@f279d4 main
[junit4] 2> 2374913 T6190 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2374913 T6190 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2374913 T6191 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f279d4 main{StandardDirectoryReader(segments_2:3 _0(5.0):C1)}
[junit4] 2> 2374914 T6130 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2374915 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2374926 T6194 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2374927 T6194 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> ASYNC NEW_CORE C1598 name=collection1 org.apache.solr.core.SolrCore@1636287
[junit4] 2> 2374938 T6174 C1598 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C1599 name=collection1 org.apache.solr.core.SolrCore@4a8465
[junit4] 2> 2374939 T6185 C1599 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:53921/solr/replication&command=fetchindex&wait=true} status=0 QTime=14
[junit4] 2> 2374941 T6130 oas.SolrTestCaseJ4.tearDown ###Ending testNoWriter
[junit4] 2> 2374956 T6130 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=27017959
[junit4] 2> 2374958 T6130 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1636287
[junit4] 2> 2374958 T6130 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2374958 T6130 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2374958 T6130 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2374958 T6130 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2374960 T6130 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2374962 T6130 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2374962 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data;done=false>>]
[junit4] 2> 2374962 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data
[junit4] 2> 2374962 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index;done=false>>]
[junit4] 2> 2374962 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119916778\master\collection1\data\index
[junit4] 2> 2374964 T6130 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2375030 T6130 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=6471830
[junit4] 2> 2375032 T6130 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4a8465
[junit4] 2> 2375033 T6130 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2375033 T6130 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2375033 T6130 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2375035 T6130 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2375036 T6130 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2375038 T6130 oasc.CachingDirectoryFactory.close Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2375038 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index;done=false>>]
[junit4] 2> 2375038 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data\index
[junit4] 2> 2375038 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data;done=false>>]
[junit4] 2> 2375038 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119917336\slave\collection1\data
[junit4] 2> 2375038 T6130 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2375170 T6130 oas.SolrTestCaseJ4.setUp ###Starting doTestReplicateAfterStartupWithNoActivity
[junit4] 2> 2375193 T6130 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2375201 T6130 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53936
[junit4] 2> 2375202 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2375203 T6130 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2375203 T6130 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master
[junit4] 2> 2375203 T6130 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\'
[junit4] 2> 2375241 T6130 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\solr.xml
[junit4] 2> 2375332 T6130 oasc.CoreContainer.<init> New CoreContainer 7121066
[junit4] 2> 2375333 T6130 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\]
[junit4] 2> 2375335 T6130 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2375335 T6130 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2375335 T6130 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2375335 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2375335 T6130 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2375335 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2375335 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2375335 T6130 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2375337 T6130 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2375342 T6130 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2375342 T6130 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2375351 T6205 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\collection1
[junit4] 2> 2375351 T6205 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\collection1\'
[junit4] 2> 2375419 T6205 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2375488 T6205 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2375489 T6205 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2375496 T6205 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2375523 T6205 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2375525 T6205 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2375525 T6205 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\collection1\data\
[junit4] 2> 2375525 T6205 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2375526 T6205 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\collection1\data\
[junit4] 2> 2375526 T6205 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\collection1\data\index/
[junit4] 2> 2375526 T6205 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2375527 T6205 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\collection1\data\index
[junit4] 2> 2375527 T6205 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=11.888671875, floorSegmentMB=2.19140625, forceMergeDeletesPctAllowed=26.83044167394666, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2375528 T6205 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7f9a3b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@174a355),segFN=segments_1,generation=1}
[junit4] 2> 2375528 T6205 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2375529 T6205 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2375531 T6205 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2375531 T6205 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2375531 T6205 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2375531 T6205 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2375532 T6205 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2375532 T6205 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2375541 T6205 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2375546 T6205 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2375546 T6205 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2375548 T6205 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=88.3447265625, floorSegmentMB=1.1123046875, forceMergeDeletesPctAllowed=5.603673704399746, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2375548 T6205 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7f9a3b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@174a355),segFN=segments_1,generation=1}
[junit4] 2> 2375548 T6205 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2375548 T6205 oass.SolrIndexSearcher.<init> Opening Searcher@159837 main
[junit4] 2> 2375548 T6205 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2375548 T6205 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2375550 T6205 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2375550 T6206 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@159837 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2375551 T6130 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2375551 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2375569 T6130 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2375576 T6130 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:53939
[junit4] 2> 2375578 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2375579 T6130 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2375579 T6130 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave
[junit4] 2> 2375579 T6130 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\'
[junit4] 2> 2375601 T6130 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\solr.xml
[junit4] 2> 2375681 T6130 oasc.CoreContainer.<init> New CoreContainer 23821382
[junit4] 2> 2375681 T6130 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\]
[junit4] 2> 2375683 T6130 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 2375683 T6130 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2375683 T6130 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 2375683 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2375683 T6130 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2375683 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2375685 T6130 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2375685 T6130 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2375685 T6130 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2375688 T6130 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2375688 T6130 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2375696 T6217 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1
[junit4] 2> 2375697 T6217 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\'
[junit4] 2> 2375763 T6217 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 2375832 T6217 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2375833 T6217 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2375840 T6217 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2375865 T6217 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2375867 T6217 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2375867 T6217 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\data\
[junit4] 2> 2375868 T6217 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2375868 T6217 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\data\
[junit4] 2> 2375868 T6217 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\data\index/
[junit4] 2> 2375869 T6217 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2375869 T6217 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\data\index
[junit4] 2> 2375869 T6217 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=11.888671875, floorSegmentMB=2.19140625, forceMergeDeletesPctAllowed=26.83044167394666, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2375870 T6217 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9e00b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@90e5b9),segFN=segments_1,generation=1}
[junit4] 2> 2375870 T6217 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2375871 T6217 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2375872 T6217 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2375872 T6217 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2375872 T6217 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2375872 T6217 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2375873 T6217 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2375873 T6217 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2375877 T6217 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2375881 T6217 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2375882 T6217 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2375882 T6217 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=88.3447265625, floorSegmentMB=1.1123046875, forceMergeDeletesPctAllowed=5.603673704399746, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2375883 T6217 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d9e00b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@90e5b9),segFN=segments_1,generation=1}
[junit4] 2> 2375883 T6217 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2375883 T6217 oass.SolrIndexSearcher.<init> Opening Searcher@4f681b main
[junit4] 2> 2375885 T6217 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2375885 T6217 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2375885 T6218 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4f681b main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 2375886 T6130 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2375886 T6130 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2375901 T6130 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=23821382
[junit4] 2> 2375903 T6130 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1adf0ff
[junit4] 2> 2375904 T6130 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2375904 T6130 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2375904 T6130 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2375904 T6130 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2375906 T6130 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2375906 T6130 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2375906 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\data\index;done=false>>]
[junit4] 2> 2375906 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\data\index
[junit4] 2> 2375906 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\data\;done=false>>]
[junit4] 2> 2375906 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919971\slave\collection1\data\
[junit4] 2> 2375910 T6130 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> ASYNC NEW_CORE C1600 name=collection1 org.apache.solr.core.SolrCore@c3e907
[junit4] 2> 2375973 T6200 C1600 oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 2375973 T6200 C1600 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*} 0 0
[junit4] 2> 2375979 T6201 C1600 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2375980 T6201 C1600 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7f9a3b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@174a355),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@7f9a3b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@174a355),segFN=segments_2,generation=2}
[junit4] 2> 2375981 T6201 C1600 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2375982 T6201 C1600 oass.SolrIndexSearcher.<init> Opening Searcher@d25f32 main
[junit4] 2> 2375983 T6201 C1600 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2375983 T6206 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d25f32 main{StandardDirectoryReader(segments_2:2:nrt)}
[junit4] 2> 2375984 T6201 C1600 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 2> 2376003 T6130 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=7121066
[junit4] 2> 2376004 T6130 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@c3e907
[junit4] 2> 2376005 T6130 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 2376006 T6130 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2376006 T6130 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2376006 T6130 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2376008 T6130 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2376008 T6130 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2376008 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\collection1\data\index;done=false>>]
[junit4] 2> 2376008 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119919589\master\collection1\data\index
[junit4] 2> 2376008 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.h
[...truncated too long message...]
-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964136\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964136\master\collection1\data\index;done=false>>]
[junit4] 2> 2421357 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964136\master\collection1\data\index
[junit4] 2> 2421357 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964136\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964136\master\collection1\data\;done=false>>]
[junit4] 2> 2421358 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964136\master\collection1\data\
[junit4] 2> 2421358 T6130 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2421418 T6130 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=24970386
[junit4] 2> 2421419 T6130 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@19c15b9
[junit4] 2> 2421419 T6130 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2421420 T6130 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2421421 T6130 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2421421 T6130 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2421421 T6130 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2421457 T6130 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2421457 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964350\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964350\slave\collection1\data\index;done=false>>]
[junit4] 2> 2421458 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964350\slave\collection1\data\index
[junit4] 2> 2421458 T6130 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964350\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964350\slave\collection1\data\;done=false>>]
[junit4] 2> 2421458 T6130 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1383119964350\slave\collection1\data\
[junit4] 2> 2421459 T6130 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2421534 T6130 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=pt_PT, timezone=America/Iqaluit
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_45 (32-bit)/cpus=2,threads=1,free=133279192,total=305659904
[junit4] 2> NOTE: All tests run in this JVM: [ZkSolrClientTest, CSVRequestHandlerTest, TestSolrXml, SuggesterWFSTTest, ConvertedLegacyTest, TestRTGBase, TestBinaryField, SuggesterTSTTest, TestNoOpRegenerator, TestPartialUpdateDeduplication, LukeRequestHandlerTest, TestRemoteStreaming, RecoveryZkTest, TestSolrXMLSerializer, DocumentBuilderTest, StandardRequestHandlerTest, UniqFieldsUpdateProcessorFactoryTest, BJQParserTest, TestIBSimilarityFactory, MBeansHandlerTest, LeaderElectionIntegrationTest, TestMaxScoreQueryParser, TestBadConfig, CacheHeaderTest, JSONWriterTest, OverseerTest, HdfsSyncSliceTest, BadComponentTest, TestReversedWildcardFilterFactory, TestSolrXmlPersistence, URLClassifyProcessorTest, TestHashPartitioner, SuggesterFSTTest, ClusterStateUpdateTest, TestUniqueKeyFieldResource, SolrInfoMBeanTest, BasicFunctionalityTest, DebugComponentTest, TestJmxIntegration, TestManagedSchema, TestRecovery, TestSolr4Spatial, TestRangeQuery, SolrCoreCheckLockOnStartupTest, SpatialFilterTest, XsltUpdateRequestHandlerTest, TestCloudManagedSchema, TestSolrQueryParserResource, DistributedTermsComponentTest, TestFunctionQuery, TestJmxMonitoredMap, SolrIndexSplitterTest, TestDynamicFieldCollectionResource, TestZkChroot, DocumentAnalysisRequestHandlerTest, ChaosMonkeySafeLeaderTest, LeaderElectionTest, MinimalSchemaTest, TestDefaultSearchFieldResource, TestMergePolicyConfig, TestCSVResponseWriter, PingRequestHandlerTest, SignatureUpdateProcessorFactoryTest, ShowFileRequestHandlerTest, TestCollationField, BasicDistributedZkTest, QueryEqualityTest, NotRequiredUniqueKeyTest, DocValuesTest, SchemaVersionSpecificBehaviorTest, TestPHPSerializedResponseWriter, TestCollapseQParserPlugin, UpdateRequestProcessorFactoryTest, TestSolrQueryParserDefaultOperatorResource, TestFoldingMultitermQuery, TestSolrDeletionPolicy1, StatelessScriptUpdateProcessorFactoryTest, TestDefaultSimilarityFactory, SoftAutoCommitTest, TestWriterPerf, SolrXmlInZkTest, TestIndexSearcher, TestCodecSupport, TestStressRecovery, TestTrie, CachingDirectoryFactoryTest, TestQuerySenderListener, TermVectorComponentDistributedTest, TestFaceting, SearchHandlerTest, SolrIndexConfigTest, TestSolrQueryParser, TestLMJelinekMercerSimilarityFactory, TermsComponentTest, UnloadDistributedZkTest, RequiredFieldsTest, LoggingHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, HdfsLockFactoryTest, TestSolrIndexConfig, FileBasedSpellCheckerTest, TestCloudManagedSchemaAddField, FieldMutatingUpdateProcessorTest, TestFastWriter, QueryParsingTest, TestNonNRTOpen, DistributedQueryElevationComponentTest, TestLRUCache, CustomCollectionTest, AssignTest, SolrCmdDistributorTest, TestBinaryResponseWriter, DistributedSpellCheckComponentTest, TestStressVersions, QueryElevationComponentTest, CoreAdminHandlerTest, ShardRoutingCustomTest, ChaosMonkeyNothingIsSafeTest, TestClassNameShortening, TestJoin, TestSweetSpotSimilarityFactory, TestBM25SimilarityFactory, SolrPluginUtilsTest, SpellingQueryConverterTest, TestDocumentBuilder, ClusterStateTest, TestQueryUtils, BasicZkTest, HighlighterTest, PrimUtilsTest, TestPostingsSolrHighlighter, TestDFRSimilarityFactory, TestCoreContainer, PluginInfoTest, CoreAdminCreateDiscoverTest, DateFieldTest, PrimitiveFieldTypeTest, TestReloadAndDeleteDocs, PreAnalyzedUpdateProcessorTest, CollectionsAPIDistributedZkTest, TestShardHandlerFactory, SliceStateTest, TestAnalyzeInfixSuggestions, PreAnalyzedFieldTest, ZkCLITest, ResponseLogComponentTest, TestRealTimeGet, StatsComponentTest, TestFieldTypeResource, CurrencyFieldOpenExchangeTest, FastVectorHighlighterTest, DateMathParserTest, SpellCheckCollatorTest, HdfsChaosMonkeySafeLeaderTest, TestArbitraryIndexDir, TestRandomFaceting, TestConfig, MultiTermTest, TestGroupingSearch, AliasIntegrationTest, BasicDistributedZk2Test, SyncSliceTest, OpenCloseCoreStressTest, ShardRoutingTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4] Completed in 49.25s, 14 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 487 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:422: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:402: 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\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:483: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1259: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:902: There were test failures: 329 suites, 1453 tests, 1 failure, 40 ignored (11 assumptions)
Total time: 80 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_45 -server -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure