Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.x-Java7/2294/
1 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
file handle leaks:
[FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-017/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-060/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-026/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-038/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-002/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-032/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-041/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-071/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-035/replication.properties)]
Stack Trace:
java.lang.RuntimeException: file handle leaks:
[FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-017/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-060/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-026/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-038/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-002/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-032/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-041/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-071/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-035/replication.properties)]
at __randomizedtesting.SeedInfo.seed([B01A6E5F1F814938]:0)
at org.apache.lucene.mockfile.LeakFS.onClose(LeakFS.java:64)
at
org.apache.lucene.mockfile.FilterFileSystem.close(FilterFileSystem.java:78)
at
org.apache.lucene.mockfile.FilterFileSystem.close(FilterFileSystem.java:79)
at
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:182)
at
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
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:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.Exception
at org.apache.lucene.mockfile.LeakFS.onOpen(LeakFS.java:47)
at
org.apache.lucene.mockfile.HandleTrackingFS.callOpenHook(HandleTrackingFS.java:84)
at
org.apache.lucene.mockfile.HandleTrackingFS.newFileChannel(HandleTrackingFS.java:173)
at
org.apache.lucene.mockfile.FilterFileSystemProvider.newFileChannel(FilterFileSystemProvider.java:180)
at
org.apache.lucene.mockfile.HandleTrackingFS.newFileChannel(HandleTrackingFS.java:151)
at java.nio.channels.FileChannel.open(FileChannel.java:287)
at java.nio.channels.FileChannel.open(FileChannel.java:334)
at
org.apache.lucene.store.NIOFSDirectory.openInput(NIOFSDirectory.java:80)
at
org.apache.lucene.util.LuceneTestCase.slowFileExists(LuceneTestCase.java:2473)
at
org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:644)
at
org.apache.solr.handler.ReplicationHandler.loadReplicationProperties(ReplicationHandler.java:827)
at
org.apache.solr.handler.SnapPuller.logReplicationTimeAndConfFiles(SnapPuller.java:573)
at
org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:512)
at
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:338)
at org.apache.solr.handler.SnapPuller$1.run(SnapPuller.java:226)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:304)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
... 1 more
Build Log:
[...truncated 9457 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/init-core-data-001
[junit4] 2> 979590 T9855 oas.SolrTestCaseJ4.setUp ###Starting
testEmptyCommits
[junit4] 2> 979606 T9855 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 979610 T9855 oejs.AbstractConnector.doStart Started
[email protected]:23211
[junit4] 2> 979611 T9855 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 979611 T9855 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 979611 T9855 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001
[junit4] 2> 979612 T9855 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/'
[junit4] 2> 979655 T9855 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/solr.xml
[junit4] 2> 979735 T9855 oasc.CoreContainer.<init> New CoreContainer
826097531
[junit4] 2> 979735 T9855 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/]
[junit4] 2> 979737 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 979737 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 979737 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 979738 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 979738 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 979738 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 979739 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 979739 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 979739 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 979740 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 979740 T9855 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 979741 T9855 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 979741 T9855 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 979741 T9855 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 979747 T9865 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/'
[junit4] 2> 979795 T9865 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 979844 T9865 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 979845 T9865 oass.IndexSchema.readSchema Reading Solr Schema
from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/conf/schema.xml
[junit4] 2> 979851 T9865 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 979868 T9865 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 979869 T9865 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from instancedir
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/
[junit4] 2> 979869 T9865 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 979869 T9865 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/data/
[junit4] 2> 979870 T9865 oasc.SolrCore.<init> JMX monitoring not detected
for core: collection1
[junit4] 2> 979871 T9865 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/data
[junit4] 2> 979873 T9865 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/data/index/
[junit4] 2> 979873 T9865 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 979874 T9865 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/data/index
[junit4] 2> 979874 T9865 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=39, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=12.73046875,
floorSegmentMB=2.0419921875, forceMergeDeletesPctAllowed=8.82342293219137,
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 979875 T9865 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_1,generation=1}
[junit4] 2> 979875 T9865 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 979876 T9865 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 979877 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 979877 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 979877 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 979878 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 979878 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 979878 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 979879 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 979879 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 979880 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 979880 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 979880 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 979881 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 979881 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 979881 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 979882 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 979882 T9865 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 979905 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 979905 T9865 oasc.RequestHandlers.initHandlersFromConfig
created defaults: solr.StandardRequestHandler
[junit4] 2> 979906 T9865 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 979906 T9865 oasc.RequestHandlers.initHandlersFromConfig
created lazy: solr.StandardRequestHandler
[junit4] 2> 979906 T9865 oasc.RequestHandlers.initHandlersFromConfig
created /update: solr.UpdateRequestHandler
[junit4] 2> 979924 T9865 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979926 T9865 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979928 T9865 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979930 T9865 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 979932 T9865 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 979932 T9865 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 979933 T9865 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=495705010,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 979934 T9865 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_1,generation=1}
[junit4] 2> 979934 T9865 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 979934 T9865 oass.SolrIndexSearcher.<init> Opening
Searcher@5416db7f[collection1] main
[junit4] 2> 979935 T9865
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage
initialized to use dir:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/conf
[junit4] 2> 979935 T9865 oasr.RestManager.init Initializing RestManager
with initArgs:
{storageDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/conf}
[junit4] 2> 979936 T9865 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/conf
[junit4] 2> 979937 T9865 oasr.ManagedResource.reloadFromStorage WARN No
stored data found for /rest/managed
[junit4] 2> 979937 T9865 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _rest_managed.json using
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/conf
[junit4] 2> 979937 T9865 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 979938 T9865 oash.ReplicationHandler.inform Replication
enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 979938 T9865 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 979938 T9866 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@5416db7f[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 979938 T9865 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 979939 T9855 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2
[junit4] 2> 979940 T9855 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 979955 T9855 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 979958 T9855 oejs.AbstractConnector.doStart Started
[email protected]:54743
[junit4] 2> 979959 T9855 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 979959 T9855 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 979960 T9855 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002
[junit4] 2> 979960 T9855 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/'
[junit4] 2> 980004 T9855 oasc.ConfigSolr.fromFile Loading container
configuration from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/solr.xml
[junit4] 2> 980083 T9855 oasc.CoreContainer.<init> New CoreContainer
2075689104
[junit4] 2> 980083 T9855 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/]
[junit4] 2> 980085 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 980085 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 980085 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 980086 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 980086 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 980086 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 980087 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 980087 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 980087 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 980088 T9855 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 980088 T9855 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 980089 T9855 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 980089 T9855 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 980090 T9855 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 980094 T9877 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/'
[junit4] 2> 980142 T9877 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 980182 T9877 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 980183 T9877 oass.IndexSchema.readSchema Reading Solr Schema
from
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/conf/schema.xml
[junit4] 2> 980190 T9877 oass.IndexSchema.readSchema [collection1] Schema
name=test
[junit4] 2> 980206 T9877 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 980207 T9877 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from instancedir
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/
[junit4] 2> 980207 T9877 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 980208 T9877 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/,
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/
[junit4] 2> 980208 T9877 oasc.SolrCore.<init> JMX monitoring not detected
for core: collection1
[junit4] 2> 980210 T9877 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data
[junit4] 2> 980211 T9877 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index/
[junit4] 2> 980211 T9877 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 980212 T9877 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index
[junit4] 2> 980212 T9877 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=39, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=12.73046875,
floorSegmentMB=2.0419921875, forceMergeDeletesPctAllowed=8.82342293219137,
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 980213 T9877 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_1,generation=1}
[junit4] 2> 980213 T9877 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 980214 T9877 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 980215 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 980215 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 980215 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 980216 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 980216 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 980216 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 980217 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 980217 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 980218 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 980218 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 980218 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 980219 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 980219 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 980219 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 980220 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 980220 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 980220 T9877 oasc.RequestHandlers.initHandlersFromConfig
created standard: solr.StandardRequestHandler
[junit4] 2> 980220 T9877 oasc.RequestHandlers.initHandlersFromConfig
created defaults: solr.StandardRequestHandler
[junit4] 2> 980221 T9877 oasc.RequestHandlers.initHandlersFromConfig
adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 980221 T9877 oasc.RequestHandlers.initHandlersFromConfig
created lazy: solr.StandardRequestHandler
[junit4] 2> 980221 T9877 oasc.RequestHandlers.initHandlersFromConfig
created /replication: solr.ReplicationHandler
[junit4] 2> 980223 T9877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 980225 T9877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 980227 T9877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 980229 T9877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 980231 T9877 oasu.CommitTracker.<init> Hard AutoCommit:
disabled
[junit4] 2> 980231 T9877 oasu.CommitTracker.<init> Soft AutoCommit:
disabled
[junit4] 2> 980233 T9877 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=495705010,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 980233 T9877 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_1,generation=1}
[junit4] 2> 980234 T9877 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 980234 T9877 oass.SolrIndexSearcher.<init> Opening
Searcher@233346ef[collection1] main
[junit4] 2> 980234 T9877
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage
initialized to use dir:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/conf
[junit4] 2> 980235 T9877 oasr.RestManager.init Initializing RestManager
with initArgs:
{storageDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/conf}
[junit4] 2> 980235 T9877 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/conf
[junit4] 2> 980236 T9877 oasr.ManagedResource.reloadFromStorage WARN No
stored data found for /rest/managed
[junit4] 2> 980236 T9877 oasr.ManagedResourceStorage$JsonStorage.store
Saved JSON object to path _rest_managed.json using
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/conf
[junit4] 2> 980237 T9877 oasr.RestManager.init Initializing 0 registered
ManagedResources
[junit4] 2> 980243 T9877 oash.SnapPuller.startExecutorService Poll
Scheduled at an interval of 1000ms
[junit4] 2> 980243 T9878 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@233346ef[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 980243 T9877 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> 980244 T9855 oass.SolrDispatchFilter.init
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2
[junit4] 2> 980244 T9855 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C419 name=collection1
org.apache.solr.core.SolrCore@d168dde
[junit4] 2> 980247 T9858 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2}
hits=0 status=0 QTime=1
[junit4] 2> 980252 T9858 C419 oasup.LogUpdateProcessor.finish
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]}
0 1
[junit4] 2> 980255 T9858 C419 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 980257 T9858 C419 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_1,generation=1}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_2,generation=2}
[junit4] 2> 980258 T9858 C419 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 2
[junit4] 2> 980260 T9858 C419 oass.SolrIndexSearcher.<init> Opening
Searcher@306da624[collection1] main
[junit4] 2> 980260 T9858 C419 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 980260 T9866 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@306da624[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1)))}
[junit4] 2> 980261 T9858 C419 oasup.LogUpdateProcessor.finish
[collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2} {commit=} 0 6
[junit4] 2> 980288 T9881 oash.SnapPuller.<init> WARN 'masterUrl' must be
specified without the /replication suffix
[junit4] 2> 980289 T9881 oash.SnapPuller.<init> No value set for
'pollInterval'. Timer Task not started.
[junit4] 2> 980290 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0
QTime=0
[junit4] 2> 980291 T9881 oash.SnapPuller.fetchLatestIndex Master's
generation: 2
[junit4] 2> 980291 T9881 oash.SnapPuller.fetchLatestIndex Slave's
generation: 1
[junit4] 2> 980291 T9881 oash.SnapPuller.fetchLatestIndex Starting
replication process
[junit4] 2> 980302 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=filelist&generation=2&wt=javabin&qt=/replication&version=2}
status=0 QTime=8
[junit4] 2> 980303 T9881 oash.SnapPuller.fetchLatestIndex Number of files
in latest index in master: 10
[junit4] 2> 980305 T9881 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091550919
[junit4] 2> 980305 T9881 oasu.DefaultSolrCoreState.closeIndexWriter
Closing IndexWriter...
[junit4] 2> 980306 T9881 oasu.DefaultSolrCoreState.closeIndexWriter
Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 980306 T9881 oasu.DefaultSolrCoreState.closeIndexWriter
Rollback old IndexWriter... core=collection1
[junit4] 2> 980306 T9881 oash.SnapPuller.fetchLatestIndex Starting
download to
MockDirectoryWrapper(NIOFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-003
lockFactory=org.apache.lucene.store.NativeFSLockFactory@23f9dfa9)
fullCopy=false
[junit4] 2> 980310 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_0.fdx&checksum=true&command=filecontent&generation=2&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980315 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_0.fnm&checksum=true&command=filecontent&generation=2&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980319 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_0_LuceneFixedGap_0.doc&checksum=true&command=filecontent&generation=2&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980323 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_0.fdt&checksum=true&command=filecontent&generation=2&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980327 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_0_LuceneFixedGap_0.tii&checksum=true&command=filecontent&generation=2&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980331 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_0.si&checksum=true&command=filecontent&generation=2&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980335 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_0_LuceneFixedGap_0.tib&checksum=true&command=filecontent&generation=2&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980339 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_0.nvd&checksum=true&command=filecontent&generation=2&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980343 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_0.nvm&checksum=true&command=filecontent&generation=2&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980347 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=segments_2&checksum=true&command=filecontent&generation=2&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980348 T9881 oash.SnapPuller.fetchLatestIndex Total time taken
for download : 0 secs
[junit4] 2> 980357 T9881 oasu.DefaultSolrCoreState.openIndexWriter
Creating new IndexWriter...
[junit4] 2> 980358 T9881 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1305874429,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 980359 T9881 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_1,generation=1}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_2,generation=2}
[junit4] 2> 980359 T9881 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 2
[junit4] 2> 980360 T9881 oasu.DefaultSolrCoreState.openIndexWriter New
IndexWriter is ready to be used.
[junit4] 2> 980361 T9881 oass.SolrIndexSearcher.<init> Opening
Searcher@106215f4[collection1] main
[junit4] 2> 980361 T9878 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@106215f4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1)))}
[junit4] 2> 980362 T9881 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091550919
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091550919;done=true>>]
[junit4] 2> 980362 T9881 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091550919
[junit4] 2> ASYNC NEW_CORE C420 name=collection1
org.apache.solr.core.SolrCore@78605dcd
[junit4] 2> 980362 T9870 C420 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={masterUrl=http://127.0.0.1:23211/solr/replication&command=fetchindex&wait=true}
status=0 QTime=74
[junit4] 2> 980369 T9871 C420 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select
params={sort=id+desc&q=name:empty1&wt=javabin&version=2} hits=1 status=0
QTime=1
[junit4] 2> 980370 T9855 oash.TestReplicationHandler.rQuery Waited for 0ms
and found 1 docs
[junit4] 2> 980373 T9858 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980376 T9858 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980383 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&wt=javabin&qt=/replication&version=2&slave=false}
status=0 QTime=0
[junit4] 2> 980384 T9871 C420 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2}
status=0 QTime=5
[junit4] 2> 980387 T9858 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980388 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0
QTime=0
[junit4] 2> 980389 T9879 oash.SnapPuller.fetchLatestIndex Slave in sync
with master.
[junit4] 2> 980390 T9871 C420 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980393 T9858 C419 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 980393 T9858 C419 oasu.DirectUpdateHandler2.commit No
uncommitted changes. Skipping IW.commit.
[junit4] 2> 980394 T9858 C419 oasc.SolrCore.openNewSearcher
SolrIndexSearcher has not changed - not re-opening:
org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 980394 T9858 C419 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 980394 T9858 C419 oasup.LogUpdateProcessor.finish
[collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2} {commit=} 0 1
[junit4] 2> 980396 T9884 oash.SnapPuller.<init> WARN 'masterUrl' must be
specified without the /replication suffix
[junit4] 2> 980397 T9884 oash.SnapPuller.<init> No value set for
'pollInterval'. Timer Task not started.
[junit4] 2> 980399 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0
QTime=0
[junit4] 2> 980400 T9884 oash.SnapPuller.fetchLatestIndex Slave in sync
with master.
[junit4] 2> 980400 T9870 C420 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={masterUrl=http://127.0.0.1:23211/solr/replication&command=fetchindex&wait=true}
status=0 QTime=4
[junit4] 2> 980404 T9858 C419 oasup.LogUpdateProcessor.finish
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]}
0 1
[junit4] 2> 980406 T9858 C419 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 980408 T9858 C419 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_2,generation=2}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_3,generation=3}
[junit4] 2> 980409 T9858 C419 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 3
[junit4] 2> 980410 T9858 C419 oass.SolrIndexSearcher.<init> Opening
Searcher@1503419b[collection1] main
[junit4] 2> 980410 T9858 C419 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 980410 T9866 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1503419b[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1)
Uninverting(_1(5.0.0):C1)))}
[junit4] 2> 980411 T9858 C419 oasup.LogUpdateProcessor.finish
[collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2} {commit=} 0 5
[junit4] 2> 980413 T9885 oash.SnapPuller.<init> WARN 'masterUrl' must be
specified without the /replication suffix
[junit4] 2> 980413 T9885 oash.SnapPuller.<init> No value set for
'pollInterval'. Timer Task not started.
[junit4] 2> 980416 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0
QTime=0
[junit4] 2> 980417 T9885 oash.SnapPuller.fetchLatestIndex Master's
generation: 3
[junit4] 2> 980417 T9885 oash.SnapPuller.fetchLatestIndex Slave's
generation: 2
[junit4] 2> 980417 T9885 oash.SnapPuller.fetchLatestIndex Starting
replication process
[junit4] 2> 980420 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=filelist&generation=3&wt=javabin&qt=/replication&version=2}
status=0 QTime=1
[junit4] 2> 980420 T9885 oash.SnapPuller.fetchLatestIndex Number of files
in latest index in master: 19
[junit4] 2> 980422 T9885 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551037
[junit4] 2> 980423 T9885 oasu.DefaultSolrCoreState.closeIndexWriter
Closing IndexWriter...
[junit4] 2> 980423 T9885 oasu.DefaultSolrCoreState.closeIndexWriter
Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 980424 T9885 oasu.DefaultSolrCoreState.closeIndexWriter
Rollback old IndexWriter... core=collection1
[junit4] 2> 980424 T9885 oash.SnapPuller.fetchLatestIndex Starting
download to
MockDirectoryWrapper(NIOFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-004
lockFactory=org.apache.lucene.store.NativeFSLockFactory@23f9dfa9)
fullCopy=false
[junit4] 2> 980424 T9885 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.fnm because it already exists
[junit4] 2> 980428 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_1.nvd&checksum=true&command=filecontent&generation=3&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980429 T9885 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.si because it already exists
[junit4] 2> 980429 T9885 oash.SnapPuller.downloadIndexFiles Skipping
download for _0_LuceneFixedGap_0.tii because it already exists
[junit4] 2> 980433 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_1_LuceneFixedGap_0.doc&checksum=true&command=filecontent&generation=3&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980434 T9885 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.nvd because it already exists
[junit4] 2> 980434 T9885 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.nvm because it already exists
[junit4] 2> 980438 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_1_LuceneFixedGap_0.tii&checksum=true&command=filecontent&generation=3&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980439 T9885 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.fdx because it already exists
[junit4] 2> 980443 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_1.fnm&checksum=true&command=filecontent&generation=3&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980447 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=segments_3&checksum=true&command=filecontent&generation=3&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980448 T9885 oash.SnapPuller.downloadIndexFiles Skipping
download for _0_LuceneFixedGap_0.doc because it already exists
[junit4] 2> 980448 T9885 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.fdt because it already exists
[junit4] 2> 980452 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_1.nvm&checksum=true&command=filecontent&generation=3&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980453 T9885 oash.SnapPuller.downloadIndexFiles Skipping
download for _0_LuceneFixedGap_0.tib because it already exists
[junit4] 2> 980456 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_1.fdt&checksum=true&command=filecontent&generation=3&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980460 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_1.si&checksum=true&command=filecontent&generation=3&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980464 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_1.fdx&checksum=true&command=filecontent&generation=3&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980468 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_1_LuceneFixedGap_0.tib&checksum=true&command=filecontent&generation=3&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980469 T9885 oash.SnapPuller.fetchLatestIndex Total time taken
for download : 0 secs
[junit4] 2> 980479 T9885 oasu.DefaultSolrCoreState.openIndexWriter
Creating new IndexWriter...
[junit4] 2> 980479 T9885 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1305874429,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 980481 T9885 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_2,generation=2}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_3,generation=3}
[junit4] 2> 980481 T9885 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 3
[junit4] 2> 980481 T9885 oasu.DefaultSolrCoreState.openIndexWriter New
IndexWriter is ready to be used.
[junit4] 2> 980483 T9885 oass.SolrIndexSearcher.<init> Opening
Searcher@4524bf98[collection1] main
[junit4] 2> 980483 T9878 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@4524bf98[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1)
Uninverting(_1(5.0.0):C1)))}
[junit4] 2> 980484 T9885 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551037
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551037;done=true>>]
[junit4] 2> 980484 T9885 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551037
[junit4] 2> 980484 T9870 C420 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={masterUrl=http://127.0.0.1:23211/solr/replication&command=fetchindex&wait=true}
status=0 QTime=71
[junit4] 2> 980488 T9871 C420 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select
params={sort=id+desc&q=name:empty2&wt=javabin&version=2} hits=1 status=0
QTime=1
[junit4] 2> 980488 T9855 oash.TestReplicationHandler.rQuery Waited for 0ms
and found 1 docs
[junit4] 2> 980491 T9858 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980494 T9858 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980500 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&wt=javabin&qt=/replication&version=2&slave=false}
status=0 QTime=0
[junit4] 2> 980501 T9871 C420 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2}
status=0 QTime=4
[junit4] 2> 980504 T9858 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980507 T9871 C420 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980511 T9858 C419 oasup.LogUpdateProcessor.finish
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]}
0 1
[junit4] 2> 980514 T9858 C419 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 980516 T9858 C419 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=3
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_2,generation=2}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_3,generation=3}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_4,generation=4}
[junit4] 2> 980517 T9858 C419 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 4
[junit4] 2> 980518 T9858 C419 oass.SolrIndexSearcher.<init> Opening
Searcher@6edb1f0[collection1] realtime
[junit4] 2> 980519 T9858 C419 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 980519 T9858 C419 oasup.LogUpdateProcessor.finish
[collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&openSearcher=false&version=2} {commit=} 0 5
[junit4] 2> 980520 T9887 oash.SnapPuller.<init> WARN 'masterUrl' must be
specified without the /replication suffix
[junit4] 2> 980521 T9887 oash.SnapPuller.<init> No value set for
'pollInterval'. Timer Task not started.
[junit4] 2> 980523 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0
QTime=0
[junit4] 2> 980524 T9887 oash.SnapPuller.fetchLatestIndex Master's
generation: 4
[junit4] 2> 980524 T9887 oash.SnapPuller.fetchLatestIndex Slave's
generation: 3
[junit4] 2> 980524 T9887 oash.SnapPuller.fetchLatestIndex Starting
replication process
[junit4] 2> 980528 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=filelist&generation=4&wt=javabin&qt=/replication&version=2}
status=0 QTime=1
[junit4] 2> 980528 T9887 oash.SnapPuller.fetchLatestIndex Number of files
in latest index in master: 28
[junit4] 2> 980531 T9887 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551145
[junit4] 2> 980531 T9887 oasu.DefaultSolrCoreState.closeIndexWriter
Closing IndexWriter...
[junit4] 2> 980532 T9887 oasu.DefaultSolrCoreState.closeIndexWriter
Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 980532 T9887 oasu.DefaultSolrCoreState.closeIndexWriter
Rollback old IndexWriter... core=collection1
[junit4] 2> 980532 T9887 oash.SnapPuller.fetchLatestIndex Starting
download to
MockDirectoryWrapper(NIOFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-005
lockFactory=org.apache.lucene.store.NativeFSLockFactory@23f9dfa9)
fullCopy=false
[junit4] 2> 980536 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_2.fdx&checksum=true&command=filecontent&generation=4&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980537 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _1_LuceneFixedGap_0.doc because it already exists
[junit4] 2> 980537 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _0_LuceneFixedGap_0.tii because it already exists
[junit4] 2> 980538 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.si because it already exists
[junit4] 2> 980541 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_2_LuceneFixedGap_0.doc&checksum=true&command=filecontent&generation=4&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980542 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.fdt because it already exists
[junit4] 2> 980542 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.nvm because it already exists
[junit4] 2> 980546 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_2.nvm&checksum=true&command=filecontent&generation=4&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980547 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.fdx because it already exists
[junit4] 2> 980547 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.fnm because it already exists
[junit4] 2> 980547 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.nvd because it already exists
[junit4] 2> 980551 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_2.si&checksum=true&command=filecontent&generation=4&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980555 T9859 C419 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_2.fdt&checksum=true&command=filecontent&generation=4&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C421 name=collection1
org.apache.solr.core.SolrCore@d168dde
[junit4] 2> 980567 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_2.fnm&checksum=true&command=filecontent&generation=4&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980568 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.nvd because it already exists
[junit4] 2> 980569 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.nvm because it already exists
[junit4] 2> 980572 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_2.nvd&checksum=true&command=filecontent&generation=4&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980573 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.fdx because it already exists
[junit4] 2> 980573 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _1_LuceneFixedGap_0.tii because it already exists
[junit4] 2> 980573 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.fnm because it already exists
[junit4] 2> 980573 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _0_LuceneFixedGap_0.doc because it already exists
[junit4] 2> 980577 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_2_LuceneFixedGap_0.tii&checksum=true&command=filecontent&generation=4&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980582 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=segments_4&checksum=true&command=filecontent&generation=4&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980583 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _0_LuceneFixedGap_0.tib because it already exists
[junit4] 2> 980583 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.si because it already exists
[junit4] 2> 980583 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.fdt because it already exists
[junit4] 2> 980587 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_2_LuceneFixedGap_0.tib&checksum=true&command=filecontent&generation=4&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980588 T9887 oash.SnapPuller.downloadIndexFiles Skipping
download for _1_LuceneFixedGap_0.tib because it already exists
[junit4] 2> 980588 T9887 oash.SnapPuller.fetchLatestIndex Total time taken
for download : 0 secs
[junit4] 2> 980596 T9887 oasu.DefaultSolrCoreState.openIndexWriter
Creating new IndexWriter...
[junit4] 2> 980597 T9887 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1305874429,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 980599 T9887 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=3
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_2,generation=2}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_3,generation=3}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_4,generation=4}
[junit4] 2> 980599 T9887 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 4
[junit4] 2> 980599 T9887 oasu.DefaultSolrCoreState.openIndexWriter New
IndexWriter is ready to be used.
[junit4] 2> 980601 T9887 oass.SolrIndexSearcher.<init> Opening
Searcher@4e137832[collection1] main
[junit4] 2> 980602 T9878 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@4e137832[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1)
Uninverting(_1(5.0.0):C1) Uninverting(_2(5.0.0):C1)))}
[junit4] 2> 980602 T9887 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551145
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551145;done=true>>]
[junit4] 2> 980602 T9887 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551145
[junit4] 2> ASYNC NEW_CORE C422 name=collection1
org.apache.solr.core.SolrCore@78605dcd
[junit4] 2> 980603 T9870 C422 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={masterUrl=http://127.0.0.1:23211/solr/replication&command=fetchindex&wait=true}
status=0 QTime=83
[junit4] 2> 980606 T9858 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select
params={sort=id+desc&q=name:empty3&wt=javabin&version=2} hits=0 status=0
QTime=0
[junit4] 2> 980607 T9855 oash.TestReplicationHandler.rQuery Waited for 0ms
and found 0 docs
[junit4] 2> 980611 T9871 C422 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select
params={sort=id+desc&q=name:empty3&wt=javabin&version=2} hits=1 status=0
QTime=1
[junit4] 2> 980612 T9855 oash.TestReplicationHandler.rQuery Waited for 0ms
and found 1 docs
[junit4] 2> 980615 T9858 C421 oasup.LogUpdateProcessor.finish
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]}
0 1
[junit4] 2> 980618 T9858 C421 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 980620 T9858 C421 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=4
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_2,generation=2}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_3,generation=3}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_4,generation=4}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@30991c64
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@14170229),segFN=segments_5,generation=5}
[junit4] 2> 980621 T9858 C421 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 5
[junit4] 2> 980622 T9858 C421 oass.SolrIndexSearcher.<init> Opening
Searcher@1cce2520[collection1] main
[junit4] 2> 980623 T9858 C421 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 980623 T9866 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1cce2520[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1)
Uninverting(_1(5.0.0):C1) Uninverting(_2(5.0.0):C1)
Uninverting(_3(5.0.0):C1)))}
[junit4] 2> 980624 T9858 C421 oasup.LogUpdateProcessor.finish
[collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2} {commit=} 0 6
[junit4] 2> 980626 T9889 oash.SnapPuller.<init> WARN 'masterUrl' must be
specified without the /replication suffix
[junit4] 2> 980626 T9889 oash.SnapPuller.<init> No value set for
'pollInterval'. Timer Task not started.
[junit4] 2> 980629 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&wt=javabin&qt=/replication&version=2} status=0
QTime=0
[junit4] 2> 980630 T9889 oash.SnapPuller.fetchLatestIndex Master's
generation: 5
[junit4] 2> 980630 T9889 oash.SnapPuller.fetchLatestIndex Slave's
generation: 4
[junit4] 2> 980630 T9889 oash.SnapPuller.fetchLatestIndex Starting
replication process
[junit4] 2> 980633 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=filelist&generation=5&wt=javabin&qt=/replication&version=2}
status=0 QTime=1
[junit4] 2> 980633 T9889 oash.SnapPuller.fetchLatestIndex Number of files
in latest index in master: 37
[junit4] 2> 980636 T9889 oasc.CachingDirectoryFactory.get return new
directory for
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551250
[junit4] 2> 980637 T9889 oasu.DefaultSolrCoreState.closeIndexWriter
Closing IndexWriter...
[junit4] 2> 980637 T9889 oasu.DefaultSolrCoreState.closeIndexWriter
Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 980637 T9889 oasu.DefaultSolrCoreState.closeIndexWriter
Rollback old IndexWriter... core=collection1
[junit4] 2> 980638 T9889 oash.SnapPuller.fetchLatestIndex Starting
download to
MockDirectoryWrapper(NIOFSDirectory@/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-006
lockFactory=org.apache.lucene.store.NativeFSLockFactory@23f9dfa9)
fullCopy=false
[junit4] 2> 980638 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _2.fdx because it already exists
[junit4] 2> 980638 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _1_LuceneFixedGap_0.doc because it already exists
[junit4] 2> 980638 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _0_LuceneFixedGap_0.tii because it already exists
[junit4] 2> 980638 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.si because it already exists
[junit4] 2> 980642 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_3_LuceneFixedGap_0.tii&checksum=true&command=filecontent&generation=5&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980643 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _2_LuceneFixedGap_0.doc because it already exists
[junit4] 2> 980647 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_3.fnm&checksum=true&command=filecontent&generation=5&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980648 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.fdt because it already exists
[junit4] 2> 980648 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.nvm because it already exists
[junit4] 2> 980648 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _2.nvm because it already exists
[junit4] 2> 980649 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.fdx because it already exists
[junit4] 2> 980649 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.fnm because it already exists
[junit4] 2> 980652 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_3_LuceneFixedGap_0.tib&checksum=true&command=filecontent&generation=5&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980653 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.nvd because it already exists
[junit4] 2> 980653 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _2.si because it already exists
[junit4] 2> 980653 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _2.fdt because it already exists
[junit4] 2> 980654 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _2.fnm because it already exists
[junit4] 2> 980657 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_3.nvm&checksum=true&command=filecontent&generation=5&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980661 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=segments_5&checksum=true&command=filecontent&generation=5&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980662 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.nvd because it already exists
[junit4] 2> 980662 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.nvm because it already exists
[junit4] 2> 980662 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _2.nvd because it already exists
[junit4] 2> 980666 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_3.fdx&checksum=true&command=filecontent&generation=5&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980667 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _0.fdx because it already exists
[junit4] 2> 980667 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _1_LuceneFixedGap_0.tii because it already exists
[junit4] 2> 980671 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_3_LuceneFixedGap_0.doc&checksum=true&command=filecontent&generation=5&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980676 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_3.si&checksum=true&command=filecontent&generation=5&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980677 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.fnm because it already exists
[junit4] 2> 980677 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _0_LuceneFixedGap_0.doc because it already exists
[junit4] 2> 980677 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _2_LuceneFixedGap_0.tii because it already exists
[junit4] 2> 980681 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_3.nvd&checksum=true&command=filecontent&generation=5&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980682 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _0_LuceneFixedGap_0.tib because it already exists
[junit4] 2> 980682 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.si because it already exists
[junit4] 2> 980682 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _1.fdt because it already exists
[junit4] 2> 980686 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={file=_3.fdt&checksum=true&command=filecontent&generation=5&wt=filestream&qt=/replication}
status=0 QTime=0
[junit4] 2> 980687 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _2_LuceneFixedGap_0.tib because it already exists
[junit4] 2> 980687 T9889 oash.SnapPuller.downloadIndexFiles Skipping
download for _1_LuceneFixedGap_0.tib because it already exists
[junit4] 2> 980687 T9889 oash.SnapPuller.fetchLatestIndex Total time taken
for download : 0 secs
[junit4] 2> 980695 T9889 oasu.DefaultSolrCoreState.openIndexWriter
Creating new IndexWriter...
[junit4] 2> 980696 T9889 oasu.RandomMergePolicy.<init> RandomMergePolicy
wrapping class org.apache.lucene.index.AlcoholicMergePolicy:
[AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1305874429,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 980698 T9889 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=4
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_2,generation=2}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_3,generation=3}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_4,generation=4}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@641c8c1f
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e337a92),segFN=segments_5,generation=5}
[junit4] 2> 980699 T9889 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 5
[junit4] 2> 980699 T9889 oasu.DefaultSolrCoreState.openIndexWriter New
IndexWriter is ready to be used.
[junit4] 2> 980702 T9889 oass.SolrIndexSearcher.<init> Opening
Searcher@d0fd340[collection1] main
[junit4] 2> 980702 T9878 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@d0fd340[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1)
Uninverting(_1(5.0.0):C1) Uninverting(_2(5.0.0):C1)
Uninverting(_3(5.0.0):C1)))}
[junit4] 2> 980702 T9889 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551250
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551250;done=true>>]
[junit4] 2> 980703 T9889 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index.20141207091551250
[junit4] 2> 980703 T9872 C422 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={masterUrl=http://127.0.0.1:23211/solr/replication&command=fetchindex&wait=true}
status=0 QTime=78
[junit4] 2> 980707 T9858 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select
params={sort=id+desc&q=name:(empty1+empty2+empty3+empty4)&wt=javabin&version=2}
hits=4 status=0 QTime=1
[junit4] 2> 980709 T9855 oash.TestReplicationHandler.rQuery Waited for 0ms
and found 4 docs
[junit4] 2> 980712 T9871 C422 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select
params={sort=id+desc&q=name:(empty1+empty2+empty3+empty4)&wt=javabin&version=2}
hits=4 status=0 QTime=1
[junit4] 2> 980713 T9855 oash.TestReplicationHandler.rQuery Waited for 0ms
and found 4 docs
[junit4] 2> 980716 T9858 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980719 T9858 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980725 T9859 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&wt=javabin&qt=/replication&version=2&slave=false}
status=0 QTime=0
[junit4] 2> 980726 T9871 C422 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2}
status=0 QTime=4
[junit4] 2> 980729 T9858 C421 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980732 T9871 C422 oasc.SolrCore.execute [collection1]
webapp=/solr path=/replication
params={command=indexversion&_trace=assertVersions&wt=javabin&qt=/replication&version=2}
status=0 QTime=0
[junit4] 2> 980733 T9855 oas.SolrTestCaseJ4.tearDown ###Ending
testEmptyCommits
[junit4] 2> 980734 T9855 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=826097531
[junit4] 2> 980735 T9855 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@d168dde
[junit4] 2> 980735 T9855 oasu.DirectUpdateHandler2.close closing
DirectUpdateHandler2{commits=5,autocommits=0,soft
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=4,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 980736 T9855 oasu.SolrCoreState.decrefSolrCoreState Closing
SolrCoreState
[junit4] 2> 980736 T9855 oasu.DefaultSolrCoreState.closeIndexWriter
SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 980737 T9855 oasu.DefaultSolrCoreState.closeIndexWriter
closing IndexWriter with IndexWriterCloser
[junit4] 2> 980738 T9855 oasc.SolrCore.closeSearcher [collection1] Closing
main searcher on request.
[junit4] 2> 980739 T9855 oasc.CachingDirectoryFactory.close Closing
MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 980739 T9855 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/data
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/data;done=false>>]
[junit4] 2> 980739 T9855 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/data
[junit4] 2> 980740 T9855 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/data/index
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/data/index;done=false>>]
[junit4] 2> 980740 T9855 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-001/collection1/data/index
[junit4] 2> 980741 T9855 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 985737 T9855 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=2075689104
[junit4] 2> 985739 T9855 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@78605dcd
[junit4] 2> 996387 T9879 oash.SnapPuller.fetchLatestIndex ERROR Master at:
http://127.0.0.1:23211/solr is not available. Index fetch failed. Exception:
IOException occured when talking to server at: http://127.0.0.1:23211/solr
[junit4] 2> 996388 T9879 oash.SnapPuller.fetchLatestIndex ERROR caught
org.apache.solr.common.SolrException:
java.nio.channels.ClosedByInterruptException
[junit4] 2> at
org.apache.solr.handler.ReplicationHandler.loadReplicationProperties(ReplicationHandler.java:847)
[junit4] 2> at
org.apache.solr.handler.SnapPuller.logReplicationTimeAndConfFiles(SnapPuller.java:573)
[junit4] 2> at
org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:512)
[junit4] 2> at
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:338)
[junit4] 2> at
org.apache.solr.handler.SnapPuller$1.run(SnapPuller.java:226)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
[junit4] 2> at
java.util.concurrent.FutureTask.runAndReset(FutureTask.java:304)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178)
[junit4] 2> at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.nio.channels.ClosedByInterruptException
[junit4] 2> at
java.nio.channels.spi.AbstractInterruptibleChannel.end(AbstractInterruptibleChannel.java:202)
[junit4] 2> at
sun.nio.ch.FileChannelImpl.size(FileChannelImpl.java:312)
[junit4] 2> at
org.apache.lucene.mockfile.FilterFileChannel.size(FilterFileChannel.java:88)
[junit4] 2> at
org.apache.lucene.mockfile.FilterFileChannel.size(FilterFileChannel.java:88)
[junit4] 2> at
org.apache.lucene.mockfile.FilterFileChannel.size(FilterFileChannel.java:88)
[junit4] 2> at
org.apache.lucene.store.NIOFSDirectory$NIOFSIndexInput.<init>(NIOFSDirectory.java:108)
[junit4] 2> at
org.apache.lucene.store.NIOFSDirectory.openInput(NIOFSDirectory.java:81)
[junit4] 2> at
org.apache.lucene.util.LuceneTestCase.slowFileExists(LuceneTestCase.java:2473)
[junit4] 2> at
org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:644)
[junit4] 2> at
org.apache.solr.handler.ReplicationHandler.loadReplicationProperties(ReplicationHandler.java:827)
[junit4] 2> ... 11 more
[junit4] 2>
[junit4] 2> 996389 T9855 oasu.DirectUpdateHandler2.close closing
DirectUpdateHandler2{commits
[...truncated too long message...]
-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data;done=false>>]
[junit4] 2> 1443392 T9855 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data
[junit4] 2> 1443392 T9855 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index;done=false>>]
[junit4] 2> 1443393 T9855 oasc.CachingDirectoryFactory.close Closing
directory:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/solr-instance-002/collection1/data/index
[junit4] 2> 1443393 T9855 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1447549 T9855 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{name=PostingsFormat(name=LuceneFixedGap),
id=PostingsFormat(name=LuceneFixedGap),
newname=PostingsFormat(name=LuceneFixedGap)}, docValues:{},
sim=DefaultSimilarity, locale=th, timezone=America/Guatemala
[junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65
(64-bit)/cpus=16,threads=1,free=153763792,total=403701760
[junit4] 2> NOTE: All tests run in this JVM: [TestPseudoReturnFields,
ChaosMonkeyNothingIsSafeTest, TestBulkSchemaAPI, SuggesterTSTTest,
SuggestComponentTest, PeerSyncTest, FastVectorHighlighterTest,
SSLMigrationTest, TestDistributedGrouping, RecoveryZkTest,
CoreAdminHandlerTest, TestCSVLoader, OpenCloseCoreStressTest,
TestLuceneMatchVersion, TestMultiCoreConfBootstrap, AsyncMigrateRouteKeyTest,
DistributedDebugComponentTest, TestHighFrequencyDictionaryFactory,
TestDistributedSearch, SuggesterTest, BinaryUpdateRequestHandlerTest,
TestModifyConfFiles, JsonLoaderTest, TestManagedSchema, SimpleFacetsTest,
TestSolrIndexConfig, TestSolrJ, TestSchemaVersionResource,
DistribDocExpirationUpdateProcessorTest, ResponseHeaderTest,
SpellCheckComponentTest, TestRandomFaceting, TestStandardQParsers,
TestSurroundQueryParser, FieldMutatingUpdateProcessorTest,
TestChildDocTransformer, SolrIndexSplitterTest, TimeZoneUtilsTest,
TestPostingsSolrHighlighter, TestBinaryField, CoreAdminRequestStatusTest,
CSVRequestHandlerTest, EchoParamsTest, AnalyticsMergeStrategyTest,
TestCollationField, RankQueryTest, LegacyHTMLStripCharFilterTest,
OverriddenZkACLAndCredentialsProvidersTest, TestReplicationHandler]
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=TestReplicationHandler -Dtests.seed=B01A6E5F1F814938
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=th
-Dtests.timezone=America/Guatemala -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J2 | TestReplicationHandler (suite) <<<
[junit4] > Throwable #1: java.lang.RuntimeException: file handle leaks:
[FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-017/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-060/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-026/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-038/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-002/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-032/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-041/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-071/replication.properties),
FileChannel(/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J2/temp/solr.handler.TestReplicationHandler-B01A6E5F1F814938-001/index-NIOFSDirectory-035/replication.properties)]
[junit4] > at
__randomizedtesting.SeedInfo.seed([B01A6E5F1F814938]:0)
[junit4] > at
org.apache.lucene.mockfile.LeakFS.onClose(LeakFS.java:64)
[junit4] > at
org.apache.lucene.mockfile.FilterFileSystem.close(FilterFileSystem.java:78)
[junit4] > at
org.apache.lucene.mockfile.FilterFileSystem.close(FilterFileSystem.java:79)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.lang.Exception
[junit4] > at
org.apache.lucene.mockfile.LeakFS.onOpen(LeakFS.java:47)
[junit4] > at
org.apache.lucene.mockfile.HandleTrackingFS.callOpenHook(HandleTrackingFS.java:84)
[junit4] > at
org.apache.lucene.mockfile.HandleTrackingFS.newFileChannel(HandleTrackingFS.java:173)
[junit4] > at
org.apache.lucene.mockfile.FilterFileSystemProvider.newFileChannel(FilterFileSystemProvider.java:180)
[junit4] > at
org.apache.lucene.mockfile.HandleTrackingFS.newFileChannel(HandleTrackingFS.java:151)
[junit4] > at
java.nio.channels.FileChannel.open(FileChannel.java:287)
[junit4] > at
java.nio.channels.FileChannel.open(FileChannel.java:334)
[junit4] > at
org.apache.lucene.store.NIOFSDirectory.openInput(NIOFSDirectory.java:80)
[junit4] > at
org.apache.lucene.util.LuceneTestCase.slowFileExists(LuceneTestCase.java:2473)
[junit4] > at
org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:644)
[junit4] > at
org.apache.solr.handler.ReplicationHandler.loadReplicationProperties(ReplicationHandler.java:827)
[junit4] > at
org.apache.solr.handler.SnapPuller.logReplicationTimeAndConfFiles(SnapPuller.java:573)
[junit4] > at
org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:512)
[junit4] > at
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:338)
[junit4] > at
org.apache.solr.handler.SnapPuller$1.run(SnapPuller.java:226)
[junit4] > at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
[junit4] > at
java.util.concurrent.FutureTask.runAndReset(FutureTask.java:304)
[junit4] > at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178)
[junit4] > at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] > ... 1 more
[junit4] Completed on J2 in 468.24s, 14 tests, 1 error <<< FAILURES!
[...truncated 880 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:529:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:477:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:61:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/extra-targets.xml:39:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build.xml:190:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/common-build.xml:508:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:1363:
The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:970:
There were test failures: 450 suites, 1844 tests, 1 suite-level error, 60
ignored (23 assumptions)
Total time: 60 minutes 28 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-Tests-5.x-Java7 #2293
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 190975634 bytes
Compression is 0.0%
Took 36 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
