Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4641/
Java: 64bit/jdk1.8.0_40 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestRestoreCore
Error Message:
Could not remove the following files (in the order of attempts):
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001: java.nio.file.DirectoryNotEmptyException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001\snapshot.jh:
java.nio.file.AccessDeniedException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001\snapshot.jh
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001: java.nio.file.DirectoryNotEmptyException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001: java.nio.file.DirectoryNotEmptyException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of
attempts):
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001: java.nio.file.DirectoryNotEmptyException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001\snapshot.jh:
java.nio.file.AccessDeniedException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001\snapshot.jh
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001: java.nio.file.DirectoryNotEmptyException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001: java.nio.file.DirectoryNotEmptyException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001
at __randomizedtesting.SeedInfo.seed([1B1B00EF9526E2B9]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:286)
at
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:200)
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)
Build Log:
[...truncated 10321 lines...]
[junit4] Suite: org.apache.solr.handler.TestRestoreCore
[junit4] 2> Creating dataDir:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\init-core-data-001
[junit4] 2> 2151881 T13981 oas.SolrTestCaseJ4.setUp ###Starting
testSimpleRestore
[junit4] 2> 2151882 T13981 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\collection1
[junit4] 2> 2151888 T13981 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 2151891 T13981 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@69f03b8b{/solr,null,AVAILABLE}
[junit4] 2> 2151892 T13981 oejs.AbstractConnector.doStart Started
ServerConnector@651796c7{HTTP/1.1}{127.0.0.1:?????}
[junit4] 2> 2151893 T13981 oejs.Server.doStart Started @???????ms
[junit4] 2> 2151893 T13981 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties:
{solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\collection1\data, hostContext=/solr,
hostPort=57773}
[junit4] 2> 2151893 T13981 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 2151893 T13981 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\'
[junit4] 2> 2151917 T13981 oasc.SolrXmlConfig.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\solr.xml
[junit4] 2> 2151925 T13981 oasc.CorePropertiesLocator.<init>
Config-defined core root directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.
[junit4] 2> 2151925 T13981 oasc.CoreContainer.<init> New CoreContainer
1581209856
[junit4] 2> 2151925 T13981 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\]
[junit4] 2> 2151926 T13981 oasc.CoreContainer.load loading shared library:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\lib
[junit4] 2> 2151926 T13981 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\lib).
[junit4] 2> 2151933 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2151933 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2151933 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2151933 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2151933 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 2151933 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2151934 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2151934 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2151934 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2151934 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2151934 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 2151937 T13981 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2151937 T13981 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2151938 T13981 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2151938 T13981 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 2151940 T13981 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.
[junit4] 2> 2151942 T13981 oasc.CoreDescriptor.<init> CORE DESCRIPTOR:
{name=collection1, config=solrconfig.xml,
instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1, loadOnStartup=true,
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\, schema=schema.xml,
transient=false, dataDir=data\}
[junit4] 2> 2151942 T13981 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\
[junit4] 2> 2151943 T13981 oasc.CorePropertiesLocator.discover Found 1
core definitions
[junit4] 2> 2151944 T13993 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\'
[junit4] 2> 2151975 T13993 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 2151978 T13993 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 2151984 T13993 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 2151984 T13993 oass.IndexSchema.readSchema Reading Solr Schema
from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\conf\schema.xml
[junit4] 2> 2151986 T13993 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 2151989 T13993 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2151990 T13993 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from instancedir
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\
[junit4] 2> 2151990 T13993 c:collection1
oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2151990 T13993 c:collection1 oasc.SolrCore.<init>
[collection1] Opening new SolrCore at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\
[junit4] 2> 2151990 T13993 c:collection1 oasc.SolrCore.<init> JMX
monitoring not detected for core: collection1
[junit4] 2> 2151991 T13993 c:collection1 oasc.CachingDirectoryFactory.get
return new directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\
[junit4] 2> 2151991 T13993 c:collection1 oasc.SolrCore.getNewIndexDir New
index directory detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\index/
[junit4] 2> 2151991 T13993 c:collection1 oasc.SolrCore.initIndex WARN
[collection1] Solr index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\index' doesn't
exist. Creating new index...
[junit4] 2> 2151992 T13993 c:collection1 oasc.CachingDirectoryFactory.get
return new directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\index
[junit4] 2> 2151992 T13993 c:collection1 oasu.RandomMergePolicy.<init>
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=24,
maxMergedSegmentMB=31.658203125, floorSegmentMB=1.6171875,
forceMergeDeletesPctAllowed=10.905731208424877, segmentsPerTier=16.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2151993 T13993 c:collection1 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4510b196
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19520243),segFN=segments_1,generation=1}
[junit4] 2> 2151993 T13993 c:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2151995 T13993 c:collection1
oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined
as default, creating implicit default
[junit4] 2> 2151998 T13993 c:collection1 oashl.XMLLoader.init
xsltCacheLifetimeSeconds=60
[junit4] 2> 2151999 T13993 c:collection1 oashl.XMLLoader.init
xsltCacheLifetimeSeconds=60
[junit4] 2> 2152000 T13993 c:collection1 oashl.XMLLoader.init
xsltCacheLifetimeSeconds=60
[junit4] 2> 2152001 T13993 c:collection1 oashl.XMLLoader.init
xsltCacheLifetimeSeconds=60
[junit4] 2> 2152003 T13993 c:collection1 oasc.PluginBag.createPlugin
requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 2152003 T13993 c:collection1
oasc.RequestHandlers.initHandlersFromConfig Registered paths:
/admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 2152003 T13993 c:collection1 oasc.SolrCore.initStatsCache
Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2152004 T13993 c:collection1 oasu.CommitTracker.<init> Hard
AutoCommit: disabled
[junit4] 2> 2152004 T13993 c:collection1 oasu.CommitTracker.<init> Soft
AutoCommit: disabled
[junit4] 2> 2152004 T13993 c:collection1 oasu.RandomMergePolicy.<init>
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=35,
maxMergedSegmentMB=53.6728515625, floorSegmentMB=1.3671875,
forceMergeDeletesPctAllowed=4.293014459277589, segmentsPerTier=36.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2152004 T13993 c:collection1 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4510b196
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19520243),segFN=segments_1,generation=1}
[junit4] 2> 2152005 T13993 c:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2152005 T13993 c:collection1 oass.SolrIndexSearcher.<init>
Opening Searcher@4cab0b4[collection1] main
[junit4] 2> 2152005 T13993 c:collection1
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage
initialized to use dir:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\conf
[junit4] 2> 2152005 T13993 c:collection1 oasr.RestManager.init
Initializing RestManager with initArgs:
{storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\conf}
[junit4] 2> 2152005 T13993 c:collection1 oasr.ManagedResourceStorage.load
Reading _rest_managed.json using
file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\conf
[junit4] 2> 2152006 T13993 c:collection1 oasr.RestManager.init
Initializing 0 registered ManagedResources
[junit4] 2> 2152006 T13993 c:collection1 oash.ReplicationHandler.inform
Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2152006 T13993 c:collection1 oash.ReplicationHandler.inform
Commits will be reserved for 10000
[junit4] 2> 2152006 T13994 c:collection1 oasc.SolrCore.registerSearcher
[collection1] Registered new searcher Searcher@4cab0b4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2152006 T13993 c:collection1 oasc.CoreContainer.registerCore
registering core: collection1
[junit4] 2> 2152007 T13981 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2152007 T13981 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C3879 name=collection1
org.apache.solr.core.SolrCore@306441a9
[junit4] 2> 2152014 T13985 c:collection1 C3879
oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM
INDEX
[junit4] 2> 2152014 T13985 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 2152018 T13986 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[0]} 0 0
[junit4] 2> 2152021 T13987 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 2152023 T13988 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 2152026 T13989 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 2152028 T13990 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 2152030 T13985 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 2152032 T13986 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[6]} 0 0
[junit4] 2> 2152034 T13987 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[7]} 0 0
[junit4] 2> 2152036 T13988 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[8]} 0 0
[junit4] 2> 2152038 T13989 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[9]} 0 0
[junit4] 2> 2152041 T13990 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 2152044 T13985 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[11]} 0 0
[junit4] 2> 2152046 T13986 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[12]} 0 0
[junit4] 2> 2152048 T13987 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[13]} 0 0
[junit4] 2> 2152049 T13988 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[14]} 0 0
[junit4] 2> 2152051 T13989 c:collection1 C3879
oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2152052 T13989 c:collection1 C3879
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4510b196
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19520243),segFN=segments_1,generation=1}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4510b196
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19520243),segFN=segments_2,generation=2}
[junit4] 2> 2152053 T13989 c:collection1 C3879
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2152053 T13989 c:collection1 C3879
oass.SolrIndexSearcher.<init> Opening Searcher@25031904[collection1] main
[junit4] 2> 2152054 T13989 c:collection1 C3879
oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2152054 T13994 c:collection1 oasc.SolrCore.registerSearcher
[collection1] Registered new searcher Searcher@25031904[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C15)))}
[junit4] 2> 2152055 T13989 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{commit=} 0 4
[junit4] 2> 2152058 T13990 c:collection1 C3879 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication params={command=backup} status=0
QTime=2
[junit4] 2> 2152058 T13997 c:collection1 oash.SnapShooter.createSnapshot
Creating backup snapshot <not named> at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\
[junit4] 2> 2152060 T13985 c:collection1 C3879 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication params={command=details} status=0
QTime=0
[junit4] 2> 2152065 T13997 c:collection1 oash.SnapShooter.createSnapshot
Done creating backup snapshot: <not named> at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\
[junit4] 2> 2153063 T13986 c:collection1 C3879 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication params={command=details} status=0
QTime=0
[junit4] 2> 2154067 T13987 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:0} 0 1
[junit4] 2> 2154070 T13988 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:1} 0 0
[junit4] 2> 2154073 T13989 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:2} 0 0
[junit4] 2> 2154076 T13990 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:3} 0 0
[junit4] 2> 2154078 T13985 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:4} 0 0
[junit4] 2> 2154080 T13986 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:5} 0 0
[junit4] 2> 2154082 T13987 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:6} 0 0
[junit4] 2> 2154084 T13988 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:7} 0 0
[junit4] 2> 2154086 T13989 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:8} 0 0
[junit4] 2> 2154088 T13990 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:9} 0 0
[junit4] 2> 2154090 T13985 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:10} 0 0
[junit4] 2> 2154091 T13986 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:11} 0 0
[junit4] 2> 2154094 T13987 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=id:12} 0 0
[junit4] 2> 2154096 T13988 c:collection1 C3879
oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2154098 T13988 c:collection1 C3879
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4510b196
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19520243),segFN=segments_2,generation=2}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4510b196
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19520243),segFN=segments_3,generation=3}
[junit4] 2> 2154098 T13988 c:collection1 C3879
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2154098 T13988 c:collection1 C3879
oass.SolrIndexSearcher.<init> Opening Searcher@7a36c4fb[collection1] main
[junit4] 2> 2154098 T13988 c:collection1 C3879
oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2154098 T13994 c:collection1 oasc.SolrCore.registerSearcher
[collection1] Registered new searcher Searcher@7a36c4fb[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C15/13:delGen=1)))}
[junit4] 2> 2154099 T13988 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{commit=} 0 3
[junit4] 2> 2154102 T13989 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[15]} 0 1
[junit4] 2> 2154104 T13990 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[16]} 0 0
[junit4] 2> 2154106 T13985 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[17]} 0 0
[junit4] 2> 2154108 T13986 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[18]} 0 0
[junit4] 2> 2154110 T13987 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[19]} 0 0
[junit4] 2> 2154112 T13988 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[20]} 0 0
[junit4] 2> 2154114 T13989 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[21]} 0 0
[junit4] 2> 2154116 T13990 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[22]} 0 0
[junit4] 2> 2154118 T13985 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[23]} 0 0
[junit4] 2> 2154120 T13986 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[24]} 0 0
[junit4] 2> 2154122 T13987 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[25]} 0 0
[junit4] 2> 2154124 T13988 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[26]} 0 0
[junit4] 2> 2154126 T13989 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[27]} 0 0
[junit4] 2> 2154128 T13990 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[28]} 0 0
[junit4] 2> 2154130 T13985 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[29]} 0 0
[junit4] 2> 2154132 T13986 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[30]} 0 0
[junit4] 2> 2154133 T13987 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[31]} 0 0
[junit4] 2> 2154135 T13988 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[32]} 0 0
[junit4] 2> 2154137 T13989 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[33]} 0 0
[junit4] 2> 2154139 T13990 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[34]} 0 0
[junit4] 2> 2154141 T13985 c:collection1 C3879
oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2154142 T13985 c:collection1 C3879
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4510b196
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19520243),segFN=segments_3,generation=3}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4510b196
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19520243),segFN=segments_4,generation=4}
[junit4] 2> 2154143 T13985 c:collection1 C3879
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 2154143 T13985 c:collection1 C3879
oass.SolrIndexSearcher.<init> Opening Searcher@f957bef[collection1] main
[junit4] 2> 2154143 T13985 c:collection1 C3879
oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2154143 T13994 c:collection1 oasc.SolrCore.registerSearcher
[collection1] Registered new searcher Searcher@f957bef[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C15/13:delGen=1)
Uninverting(_1(6.0.0):C20)))}
[junit4] 2> 2154144 T13985 c:collection1 C3879
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{commit=} 0 3
[junit4] 2> 2154146 T13986 c:collection1 C3879 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication params={command=restore} status=0
QTime=1
[junit4] 2> 2154147 T13998 c:collection1 oasc.CachingDirectoryFactory.get
return new directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\restore.snapshot.20150406155529635
[junit4] 2> 2154148 T13987 c:collection1 C3879 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication params={command=restorestatus}
status=0 QTime=1
[junit4] 2> 2154148 T13998 c:collection1 oash.RestoreCore.doRestore
Copying file segments_2 to restore directory
[junit4] 2> 2154150 T13998 c:collection1 oash.RestoreCore.doRestore
Copying file _0.fdt to restore directory
[junit4] 2> 2154150 T13998 c:collection1 oash.RestoreCore.doRestore
Copying file _0.fdx to restore directory
[junit4] 2> 2154150 T13998 c:collection1 oash.RestoreCore.doRestore
Copying file _0.fnm to restore directory
[junit4] 2> 2154150 T13998 c:collection1 oash.RestoreCore.doRestore
Copying file _0.nvd to restore directory
[junit4] 2> 2154151 T13998 c:collection1 oash.RestoreCore.doRestore
Copying file _0.nvm to restore directory
[junit4] 2> 2154152 T13998 c:collection1 oash.RestoreCore.doRestore
Copying file _0.si to restore directory
[junit4] 2> 2154152 T13998 c:collection1 oash.RestoreCore.doRestore
Copying file _0_Memory_0.ram to restore directory
[junit4] 2> 2154152 T13998 c:collection1
oash.IndexFetcher.modifyIndexProps New index installed. Updating index
properties... index=restore.snapshot.20150406155529635
[junit4] 2> 2154152 T13998 c:collection1
oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 2154152 T13998 c:collection1
oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused...
core=collection1
[junit4] 2> 2154152 T13998 c:collection1
oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter...
core=collection1
[junit4] 2> 2154153 T13998 c:collection1 oasc.SolrCore.getNewIndexDir New
index directory detected:
old=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\index/
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\restore.snapshot.20150406155529635
[junit4] 2> 2154153 T13998 c:collection1 oasu.RandomMergePolicy.<init>
RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy:
[LogDocMergePolicy: minMergeSize=1000, mergeFactor=41,
maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.14933306895474888]
[junit4] 2> 2154153 T13998 c:collection1 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@23101bb7
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@79ba2448),segFN=segments_2,generation=2}
[junit4] 2> 2154154 T13998 c:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2154154 T13998 c:collection1
oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2154155 T13998 c:collection1 oass.SolrIndexSearcher.<init>
Opening Searcher@b9314b4[collection1] main
[junit4] 2> 2154155 T13994 c:collection1 oasc.SolrCore.registerSearcher
[collection1] Registered new searcher Searcher@b9314b4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C15)))}
[junit4] 2> 2154157 T13998 c:collection1 oash.RestoreCore.doRestore
Successfully restored to the backup index
[junit4] 2> 2154157 T13998 c:collection1
oasc.CachingDirectoryFactory.closeCacheValue looking to close
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\index
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\index;done=true>>]
[junit4] 2> 2154157 T13998 c:collection1
oasc.CachingDirectoryFactory.close Closing directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\index
[junit4] 2> 2155148 T13988 c:collection1 C3879 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication params={command=restorestatus}
status=0 QTime=0
[junit4] 2> 2155151 T13989 c:collection1 C3879 oasc.SolrCore.execute
[collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2}
hits=15 status=0 QTime=0
[junit4] 2> 2155152 T13981 oas.SolrTestCaseJ4.tearDown ###Ending
testSimpleRestore
[junit4] 2> 2155153 T13981 oejs.AbstractConnector.doStop Stopped
ServerConnector@651796c7{HTTP/1.1}{127.0.0.1:?}
[junit4] 2> 2155153 T13981 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=1581209856
[junit4] 2> 2155154 T13981 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@306441a9
[junit4] 2> 2155154 T13981 oasu.DirectUpdateHandler2.close closing
DirectUpdateHandler2{commits=3,autocommits=0,soft
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=35,cumulative_deletesById=0,cumulative_deletesByQuery=14,cumulative_errors=0}
[junit4] 2> 2155155 T13981 oasu.SolrCoreState.decrefSolrCoreState Closing
SolrCoreState
[junit4] 2> 2155155 T13981 oasu.DefaultSolrCoreState.closeIndexWriter
SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2155155 T13981 oasu.DefaultSolrCoreState.closeIndexWriter
closing IndexWriter with IndexWriterCloser
[junit4] 2> 2155156 T13981 oasc.SolrCore.closeSearcher [collection1]
Closing main searcher on request.
[junit4] 2> 2155157 T13981 oasc.CachingDirectoryFactory.close Closing
MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2155157 T13981 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\;done=false>>]
[junit4] 2> 2155157 T13981 oasc.CachingDirectoryFactory.close Closing
directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\
[junit4] 2> 2155157 T13981 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\restore.snapshot.20150406155529635
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\restore.snapshot.20150406155529635;done=false>>]
[junit4] 2> 2155158 T13981 oasc.CachingDirectoryFactory.close Closing
directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-001\.\collection1\data\restore.snapshot.20150406155529635
[junit4] 2> 2155158 T13981 oejsh.ContextHandler.doStop Stopped
o.e.j.s.ServletContextHandler@69f03b8b{/solr,null,UNAVAILABLE}
[junit4] 2> 2155161 T13981 oas.SolrTestCaseJ4.setUp ###Starting
testFailedRestore
[junit4] 2> 2155162 T13981 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\collection1
[junit4] 2> 2155170 T13981 oejs.Server.doStart jetty-9.2.9.v20150224
[junit4] 2> 2155172 T13981 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@1ac1b32{/solr,null,AVAILABLE}
[junit4] 2> 2155173 T13981 oejs.AbstractConnector.doStart Started
ServerConnector@145db199{HTTP/1.1}{127.0.0.1:?????}
[junit4] 2> 2155173 T13981 oejs.Server.doStart Started @???????ms
[junit4] 2> 2155173 T13981 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties:
{solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\collection1\data, hostContext=/solr,
hostPort=57792}
[junit4] 2> 2155174 T13981 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 2155174 T13981 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\'
[junit4] 2> 2155187 T13981 oasc.SolrXmlConfig.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\solr.xml
[junit4] 2> 2155192 T13981 oasc.CorePropertiesLocator.<init>
Config-defined core root directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.
[junit4] 2> 2155193 T13981 oasc.CoreContainer.<init> New CoreContainer
603258926
[junit4] 2> 2155193 T13981 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\]
[junit4] 2> 2155193 T13981 oasc.CoreContainer.load loading shared library:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\lib
[junit4] 2> 2155193 T13981 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: lib (resolved as:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\lib).
[junit4] 2> 2155201 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting socketTimeout to: 90000
[junit4] 2> 2155201 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting urlScheme to:
[junit4] 2> 2155201 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting connTimeout to: 15000
[junit4] 2> 2155201 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnectionsPerHost to: 20
[junit4] 2> 2155202 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting maxConnections to: 10000
[junit4] 2> 2155202 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting corePoolSize to: 0
[junit4] 2> 2155202 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting maximumPoolSize to: 2147483647
[junit4] 2> 2155202 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting maxThreadIdleTime to: 5
[junit4] 2> 2155202 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting sizeOfQueue to: -1
[junit4] 2> 2155203 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting fairnessPolicy to: false
[junit4] 2> 2155203 T13981 oashc.HttpShardHandlerFactory.getParameter
Setting useRetries to: false
[junit4] 2> 2155204 T13981 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2155204 T13981 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2155205 T13981 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2155205 T13981 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 2155206 T13981 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.
[junit4] 2> 2155208 T13981 oasc.CoreDescriptor.<init> CORE DESCRIPTOR:
{name=collection1, config=solrconfig.xml,
instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1, loadOnStartup=true,
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\, schema=schema.xml,
transient=false, dataDir=data\}
[junit4] 2> 2155208 T13981 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\
[junit4] 2> 2155210 T13981 oasc.CorePropertiesLocator.discover Found 1
core definitions
[junit4] 2> 2155211 T14012 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\'
[junit4] 2> 2155243 T14012 oasc.SolrConfig.refreshRequestParams current
version of requestparams : -1
[junit4] 2> 2155245 T14012 oasc.SolrConfig.<init> Using Lucene
MatchVersion: 6.0.0
[junit4] 2> 2155251 T14012 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 2155251 T14012 oass.IndexSchema.readSchema Reading Solr Schema
from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\conf\schema.xml
[junit4] 2> 2155254 T14012 oass.IndexSchema.readSchema [collection1]
Schema name=test
[junit4] 2> 2155256 T14012 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2155256 T14012 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from instancedir
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\
[junit4] 2> 2155256 T14012 c:collection1
oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2155257 T14012 c:collection1 oasc.SolrCore.<init>
[collection1] Opening new SolrCore at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\,
dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\
[junit4] 2> 2155257 T14012 c:collection1 oasc.SolrCore.<init> JMX
monitoring not detected for core: collection1
[junit4] 2> 2155257 T14012 c:collection1 oasc.CachingDirectoryFactory.get
return new directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\
[junit4] 2> 2155257 T14012 c:collection1 oasc.SolrCore.getNewIndexDir New
index directory detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\index/
[junit4] 2> 2155257 T14012 c:collection1 oasc.SolrCore.initIndex WARN
[collection1] Solr index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\index' doesn't
exist. Creating new index...
[junit4] 2> 2155257 T14012 c:collection1 oasc.CachingDirectoryFactory.get
return new directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\index
[junit4] 2> 2155257 T14012 c:collection1 oasu.RandomMergePolicy.<init>
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=24,
maxMergedSegmentMB=31.658203125, floorSegmentMB=1.6171875,
forceMergeDeletesPctAllowed=10.905731208424877, segmentsPerTier=16.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2155258 T14012 c:collection1 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4cbb4425
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36394ecc),segFN=segments_1,generation=1}
[junit4] 2> 2155258 T14012 c:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2155260 T14012 c:collection1
oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined
as default, creating implicit default
[junit4] 2> 2155261 T14012 c:collection1 oashl.XMLLoader.init
xsltCacheLifetimeSeconds=60
[junit4] 2> 2155262 T14012 c:collection1 oashl.XMLLoader.init
xsltCacheLifetimeSeconds=60
[junit4] 2> 2155263 T14012 c:collection1 oashl.XMLLoader.init
xsltCacheLifetimeSeconds=60
[junit4] 2> 2155264 T14012 c:collection1 oashl.XMLLoader.init
xsltCacheLifetimeSeconds=60
[junit4] 2> 2155266 T14012 c:collection1 oasc.PluginBag.createPlugin
requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 2155266 T14012 c:collection1
oasc.RequestHandlers.initHandlersFromConfig Registered paths:
/admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 2155266 T14012 c:collection1 oasc.SolrCore.initStatsCache
Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2155267 T14012 c:collection1 oasu.CommitTracker.<init> Hard
AutoCommit: disabled
[junit4] 2> 2155267 T14012 c:collection1 oasu.CommitTracker.<init> Soft
AutoCommit: disabled
[junit4] 2> 2155267 T14012 c:collection1 oasu.RandomMergePolicy.<init>
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=35,
maxMergedSegmentMB=53.6728515625, floorSegmentMB=1.3671875,
forceMergeDeletesPctAllowed=4.293014459277589, segmentsPerTier=36.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 2155267 T14012 c:collection1 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4cbb4425
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36394ecc),segFN=segments_1,generation=1}
[junit4] 2> 2155267 T14012 c:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2155268 T14012 c:collection1 oass.SolrIndexSearcher.<init>
Opening Searcher@6e7afb28[collection1] main
[junit4] 2> 2155268 T14012 c:collection1
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage
initialized to use dir:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\conf
[junit4] 2> 2155268 T14012 c:collection1 oasr.RestManager.init
Initializing RestManager with initArgs:
{storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\conf}
[junit4] 2> 2155269 T14012 c:collection1 oasr.ManagedResourceStorage.load
Reading _rest_managed.json using
file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\conf
[junit4] 2> 2155269 T14012 c:collection1 oasr.RestManager.init
Initializing 0 registered ManagedResources
[junit4] 2> 2155269 T14012 c:collection1 oash.ReplicationHandler.inform
Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2155269 T14012 c:collection1 oash.ReplicationHandler.inform
Commits will be reserved for 10000
[junit4] 2> 2155269 T14012 c:collection1 oasc.CoreContainer.registerCore
registering core: collection1
[junit4] 2> 2155269 T14013 c:collection1 oasc.SolrCore.registerSearcher
[collection1] Registered new searcher Searcher@6e7afb28[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2155270 T13981 oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2155270 T13981 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> ASYNC NEW_CORE C3880 name=collection1
org.apache.solr.core.SolrCore@477f685f
[junit4] 2> 2155275 T14004 c:collection1 C3880
oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM
INDEX
[junit4] 2> 2155275 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=*:*} 0 0
[junit4] 2> 2155278 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[0]} 0 0
[junit4] 2> 2155282 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 2155284 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 2155286 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 2155288 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 2155290 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 2155292 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[6]} 0 0
[junit4] 2> 2155294 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[7]} 0 0
[junit4] 2> 2155296 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[8]} 0 0
[junit4] 2> 2155298 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[9]} 0 0
[junit4] 2> 2155299 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 2155301 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[11]} 0 0
[junit4] 2> 2155304 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[12]} 0 0
[junit4] 2> 2155306 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[13]} 0 0
[junit4] 2> 2155308 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[14]} 0 0
[junit4] 2> 2155311 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[15]} 0 0
[junit4] 2> 2155313 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[16]} 0 0
[junit4] 2> 2155315 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[17]} 0 0
[junit4] 2> 2155318 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[18]} 0 0
[junit4] 2> 2155320 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[19]} 0 0
[junit4] 2> 2155322 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[20]} 0 0
[junit4] 2> 2155324 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[21]} 0 0
[junit4] 2> 2155326 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[22]} 0 0
[junit4] 2> 2155329 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[23]} 0 0
[junit4] 2> 2155331 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[24]} 0 0
[junit4] 2> 2155333 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[25]} 0 0
[junit4] 2> 2155336 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[26]} 0 0
[junit4] 2> 2155338 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[27]} 0 0
[junit4] 2> 2155340 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[28]} 0 0
[junit4] 2> 2155342 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[29]} 0 0
[junit4] 2> 2155344 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[30]} 0 0
[junit4] 2> 2155347 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[31]} 0 0
[junit4] 2> 2155349 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[32]} 0 0
[junit4] 2> 2155351 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[33]} 0 0
[junit4] 2> 2155353 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[34]} 0 0
[junit4] 2> 2155355 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[35]} 0 0
[junit4] 2> 2155357 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[36]} 0 0
[junit4] 2> 2155361 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[37]} 0 0
[junit4] 2> 2155363 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[38]} 0 0
[junit4] 2> 2155366 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[39]} 0 0
[junit4] 2> 2155369 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[40]} 0 0
[junit4] 2> 2155371 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[41]} 0 0
[junit4] 2> 2155373 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[42]} 0 0
[junit4] 2> 2155375 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[43]} 0 0
[junit4] 2> 2155377 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[44]} 0 0
[junit4] 2> 2155379 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[45]} 0 0
[junit4] 2> 2155381 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[46]} 0 0
[junit4] 2> 2155382 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[47]} 0 0
[junit4] 2> 2155384 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[48]} 0 0
[junit4] 2> 2155386 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[49]} 0 0
[junit4] 2> 2155388 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[50]} 0 0
[junit4] 2> 2155391 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[51]} 0 0
[junit4] 2> 2155393 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[52]} 0 0
[junit4] 2> 2155395 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[53]} 0 0
[junit4] 2> 2155397 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[54]} 0 0
[junit4] 2> 2155399 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[55]} 0 0
[junit4] 2> 2155401 T14007 c:collection1 C3880
oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2155403 T14007 c:collection1 C3880
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4cbb4425
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36394ecc),segFN=segments_1,generation=1}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4cbb4425
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36394ecc),segFN=segments_2,generation=2}
[junit4] 2> 2155403 T14007 c:collection1 C3880
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2155404 T14007 c:collection1 C3880
oass.SolrIndexSearcher.<init> Opening Searcher@dad8150[collection1] main
[junit4] 2> 2155404 T14007 c:collection1 C3880
oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2155404 T14013 c:collection1 oasc.SolrCore.registerSearcher
[collection1] Registered new searcher Searcher@dad8150[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C56)))}
[junit4] 2> 2155405 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{commit=} 0 4
[junit4] 2> 2155408 T14008 c:collection1 C3880 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication
params={name=jh&location=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore+1B1B00EF9526E2B9-001\tempDir-001&command=backup}
status=0 QTime=1
[junit4] 2> 2155408 T14016 c:collection1 oash.SnapShooter.createSnapshot
Creating backup snapshot jh at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001
[junit4] 2> 2155410 T14009 c:collection1 C3880 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication params={command=details} status=0
QTime=0
[junit4] 2> 2155414 T14016 c:collection1 oash.SnapShooter.createSnapshot
Done creating backup snapshot: jh at
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001
[junit4] 2> 2156412 T14004 c:collection1 C3880 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication params={command=details} status=0
QTime=0
[junit4] 2> 2157414 T14005 c:collection1 C3880 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication
params={name=jh&location=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore+1B1B00EF9526E2B9-001\tempDir-001&command=restore}
status=0 QTime=0
[junit4] 2> 2157416 T14006 c:collection1 C3880 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication params={command=restorestatus}
status=0 QTime=0
[junit4] 2> 2157417 T14017 c:collection1 oasc.CachingDirectoryFactory.get
return new directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\restore.snapshot.jh
[junit4] 2> 2157417 T14017 c:collection1 oash.RestoreCore.doRestore
Copying file _0.fdt to restore directory
[junit4] 2> 2157418 T14017 c:collection1 oash.RestoreCore.doRestore
Copying file _0.fdx to restore directory
[junit4] 2> 2157418 T14017 c:collection1 oash.RestoreCore.doRestore
Copying file _0.fnm to restore directory
[junit4] 2> 2157419 T14017 c:collection1 oash.RestoreCore.doRestore
Copying file _0.nvd to restore directory
[junit4] 2> 2157419 T14017 c:collection1 oash.RestoreCore.doRestore
Copying file _0.nvm to restore directory
[junit4] 2> 2157419 T14017 c:collection1 oash.RestoreCore.doRestore
Copying file _0.si to restore directory
[junit4] 2> 2157419 T14017 c:collection1 oash.RestoreCore.doRestore
Copying file _0_Memory_0.ram to restore directory
[junit4] 2> 2157420 T14017 c:collection1
oash.IndexFetcher.modifyIndexProps New index installed. Updating index
properties... index=restore.snapshot.jh
[junit4] 2> 2157421 T14017 c:collection1
oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 2157421 T14017 c:collection1
oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused...
core=collection1
[junit4] 2> 2157421 T14017 c:collection1
oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter...
core=collection1
[junit4] 2> 2157421 T14017 c:collection1 oasc.SolrCore.getNewIndexDir New
index directory detected:
old=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\index/
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\restore.snapshot.jh
[junit4] 2> 2157421 T14017 c:collection1 oasu.RandomMergePolicy.<init>
RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy:
[LogDocMergePolicy: minMergeSize=1000, mergeFactor=41,
maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.14933306895474888]
[junit4] 2> 2157422 T14017 c:collection1 oash.RestoreCore.doRestore Could
not switch to restored index. Rolling back to the current index
[junit4] 2> 2157422 T14017 c:collection1
oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter...
[junit4] 2> 2157422 T14017 c:collection1
oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused...
core=collection1
[junit4] 2> 2157422 T14017 c:collection1
oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter...
core=collection1
[junit4] 2> 2157422 T14017 c:collection1 oasc.SolrCore.getNewIndexDir New
index directory detected:
old=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\restore.snapshot.jh
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\index/
[junit4] 2> 2157423 T14017 c:collection1 oasu.RandomMergePolicy.<init>
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=19, maxMergeAtOnceExplicit=48,
maxMergedSegmentMB=38.1142578125, floorSegmentMB=1.3125,
forceMergeDeletesPctAllowed=26.228125787463643, segmentsPerTier=35.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 2157423 T14017 c:collection1 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4cbb4425
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36394ecc),segFN=segments_2,generation=2}
[junit4] 2> 2157423 T14017 c:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2157423 T14017 c:collection1
oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2157424 T14017 c:collection1 oass.SolrIndexSearcher.<init>
Opening Searcher@68700376[collection1] main
[junit4] 2> 2157424 T14013 c:collection1 oasc.SolrCore.registerSearcher
[collection1] Registered new searcher Searcher@68700376[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C56)))}
[junit4] 2> 2157424 T14017 c:collection1
oasc.CachingDirectoryFactory.closeCacheValue looking to close
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\restore.snapshot.jh
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\restore.snapshot.jh;done=true>>]
[junit4] 2> 2157424 T14017 c:collection1
oasc.CachingDirectoryFactory.close Closing directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\restore.snapshot.jh
[junit4] 2> 2158419 T14007 c:collection1 C3880 oasc.SolrCore.execute
[collection1] webapp=/solr path=/replication params={command=restorestatus}
status=0 QTime=0
[junit4] 2> 2158422 T14008 c:collection1 C3880 oasc.SolrCore.execute
[collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2}
hits=56 status=0 QTime=0
[junit4] 2> 2158424 T14009 c:collection1 C3880
oasu.DirectUpdateHandler2.deleteAll [collection1] REMOVING ALL DOCUMENTS FROM
INDEX
[junit4] 2> 2158425 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {deleteByQuery=*:*} 0 1
[junit4] 2> 2158428 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[0]} 0 0
[junit4] 2> 2158432 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[1]} 0 0
[junit4] 2> 2158445 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[2]} 0 0
[junit4] 2> 2158447 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[3]} 0 0
[junit4] 2> 2158449 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[4]} 0 0
[junit4] 2> 2158451 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[5]} 0 0
[junit4] 2> 2158454 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[6]} 0 0
[junit4] 2> 2158456 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[7]} 0 0
[junit4] 2> 2158459 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[8]} 0 0
[junit4] 2> 2158461 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[9]} 0 0
[junit4] 2> 2158463 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[10]} 0 0
[junit4] 2> 2158465 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[11]} 0 0
[junit4] 2> 2158466 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[12]} 0 0
[junit4] 2> 2158468 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[13]} 0 0
[junit4] 2> 2158470 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[14]} 0 0
[junit4] 2> 2158472 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[15]} 0 0
[junit4] 2> 2158474 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[16]} 0 0
[junit4] 2> 2158476 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[17]} 0 0
[junit4] 2> 2158478 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[18]} 0 0
[junit4] 2> 2158481 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[19]} 0 0
[junit4] 2> 2158483 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[20]} 0 0
[junit4] 2> 2158485 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[21]} 0 0
[junit4] 2> 2158487 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[22]} 0 0
[junit4] 2> 2158489 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[23]} 0 0
[junit4] 2> 2158492 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[24]} 0 0
[junit4] 2> 2158495 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[25]} 0 0
[junit4] 2> 2158497 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[26]} 0 0
[junit4] 2> 2158499 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[27]} 0 0
[junit4] 2> 2158502 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[28]} 0 0
[junit4] 2> 2158504 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[29]} 0 0
[junit4] 2> 2158507 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[30]} 0 0
[junit4] 2> 2158510 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[31]} 0 0
[junit4] 2> 2158513 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[32]} 0 0
[junit4] 2> 2158517 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[33]} 0 0
[junit4] 2> 2158519 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[34]} 0 0
[junit4] 2> 2158521 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[35]} 0 0
[junit4] 2> 2158523 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[36]} 0 0
[junit4] 2> 2158525 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[37]} 0 0
[junit4] 2> 2158527 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[38]} 0 0
[junit4] 2> 2158529 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[39]} 0 0
[junit4] 2> 2158531 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[40]} 0 0
[junit4] 2> 2158534 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[41]} 0 0
[junit4] 2> 2158536 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[42]} 0 0
[junit4] 2> 2158538 T14005 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[43]} 0 0
[junit4] 2> 2158540 T14006 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[44]} 0 0
[junit4] 2> 2158543 T14007 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[45]} 0 0
[junit4] 2> 2158545 T14008 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[46]} 0 0
[junit4] 2> 2158547 T14009 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={wt=javabin&version=2} {add=[47]} 0 0
[junit4] 2> 2158549 T14004 c:collection1 C3880
oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2158550 T14004 c:collection1 C3880
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4cbb4425
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36394ecc),segFN=segments_2,generation=2}
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4cbb4425
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36394ecc),segFN=segments_3,generation=3}
[junit4] 2> 2158551 T14004 c:collection1 C3880
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2158552 T14004 c:collection1 C3880
oass.SolrIndexSearcher.<init> Opening Searcher@31c09dc[collection1] main
[junit4] 2> 2158552 T14004 c:collection1 C3880
oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2158552 T14013 c:collection1 oasc.SolrCore.registerSearcher
[collection1] Registered new searcher Searcher@31c09dc[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.0.0):C48)))}
[junit4] 2> 2158552 T14004 c:collection1 C3880
oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{commit=} 0 3
[junit4] 2> 2158555 T14005 c:collection1 C3880 oasc.SolrCore.execute
[collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2}
hits=48 status=0 QTime=0
[junit4] 2> 2158556 T13981 oas.SolrTestCaseJ4.tearDown ###Ending
testFailedRestore
[junit4] 2> 2158567 T13981 oejs.AbstractConnector.doStop Stopped
ServerConnector@145db199{HTTP/1.1}{127.0.0.1:?}
[junit4] 2> 2158567 T13981 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=603258926
[junit4] 2> 2158567 T13981 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@477f685f
[junit4] 2> 2158568 T13981 oasu.DirectUpdateHandler2.close closing
DirectUpdateHandler2{commits=2,autocommits=0,soft
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=104,cumulative_deletesById=0,cumulative_deletesByQuery=2,cumulative_errors=0}
[junit4] 2> 2158569 T13981 oasu.SolrCoreState.decrefSolrCoreState Closing
SolrCoreState
[junit4] 2> 2158570 T13981 oasu.DefaultSolrCoreState.closeIndexWriter
SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2158570 T13981 oasu.DefaultSolrCoreState.closeIndexWriter
closing IndexWriter with IndexWriterCloser
[junit4] 2> 2158572 T13981 oasc.SolrCore.closeSearcher [collection1]
Closing main searcher on request.
[junit4] 2> 2158572 T13981 oasc.CachingDirectoryFactory.close Closing
MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2158572 T13981 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\;done=false>>]
[junit4] 2> 2158572 T13981 oasc.CachingDirectoryFactory.close Closing
directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\
[junit4] 2> 2158573 T13981 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\index
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\index;done=false>>]
[junit4] 2> 2158573 T13981 oasc.CachingDirectoryFactory.close Closing
directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\solr-instance-002\.\collection1\data\index
[junit4] 2> 2158573 T13981 oejsh.ContextHandler.doStop Stopped
o.e.j.s.ServletContextHandler@1ac1b32{/solr,null,UNAVAILABLE}
[junit4] 2> 2158584 T13981 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50):
{name=PostingsFormat(name=Memory doPackFST= false),
id=PostingsFormat(name=Memory doPackFST= false)}, docValues:{},
sim=DefaultSimilarity, locale=hi_IN, timezone=America/Guayaquil
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_40
(64-bit)/cpus=3,threads=1,free=157942456,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestCollapseQParserPlugin,
IndexSchemaRuntimeFieldTest, TestCloudManagedSchemaConcurrent,
AliasIntegrationTest, TestPivotHelperCode, TestBulkSchemaConcurrent,
TestMissingGroups, TestObjectReleaseTracker, TestSchemaResource,
TestTrackingShardHandlerFactory, DocumentAnalysisRequestHandlerTest,
SolrIndexSplitterTest, TestJsonFacets, SolrPluginUtilsTest, QueryResultKeyTest,
DistributedFacetPivotSmallAdvancedTest, QueryElevationComponentTest,
TestFieldTypeCollectionResource, SimpleFacetsTest, HdfsBasicDistributedZkTest,
OutOfBoxZkACLAndCredentialsProvidersTest, TestSearcherReuse, TestConfigReload,
TestLRUStatsCache, HardAutoCommitTest, TestExactSharedStatsCache,
BlockCacheTest, TestStressVersions, CursorPagingTest, LukeRequestHandlerTest,
SpatialHeatmapFacetsTest, TestIndexingPerformance, TestExtendedDismaxParser,
URLClassifyProcessorTest, DistributedQueryComponentCustomSortTest,
ExitableDirectoryReaderTest, SoftAutoCommitTest, TestSchemaSimilarityResource,
TestOmitPositions, AnalysisErrorHandlingTest, OverseerStatusTest,
TestDefaultSearchFieldResource, AnalysisAfterCoreReloadTest,
SolrTestCaseJ4Test, DirectUpdateHandlerTest, ExternalCollectionsTest,
ChaosMonkeyNothingIsSafeTest, ZkControllerTest, TestBlobHandler,
ShowFileRequestHandlerTest, TestBulkSchemaAPI, TestCoreContainer,
TestOverriddenPrefixQueryForCustomFieldType, TestCharFilters,
TestPartialUpdateDeduplication, TestSolrConfigHandler, TestStressLucene,
CloudMLTQParserTest, CollectionReloadTest, TestDynamicLoading,
SSLMigrationTest, TestJmxIntegration, SaslZkACLProviderTest, TestLazyCores,
TestPerFieldSimilarity, TimeZoneUtilsTest, TestSolrIndexConfig,
TestFastLRUCache, DistributedFacetPivotLargeTest, HttpPartitionTest,
CircularListTest, HdfsBasicDistributedZk2Test, NumericFieldsTest,
BadComponentTest, ShardSplitTest, TestFaceting, TestDefaultStatsCache,
TestBM25SimilarityFactory, SOLR749Test, TriLevelCompositeIdRoutingTest,
DistributedFacetPivotSmallTest, DirectSolrConnectionTest,
ClusterStateUpdateTest, PreAnalyzedFieldTest, TestUpdate,
SystemInfoHandlerTest, TestManagedResource, TestAnalyzeInfixSuggestions,
PeerSyncTest, RollingRestartTest, SynonymTokenizerTest, SliceStateTest,
TestWriterPerf, TestLRUCache, TestWordDelimiterFilterFactory, ZkCLITest,
TestCSVResponseWriter, TestStressRecovery, SolrCoreCheckLockOnStartupTest,
TestExactStatsCache, TestGroupingSearch, TestPhraseSuggestions,
AnalyticsQueryTest, DocValuesMultiTest, TestSolrQueryParserResource, TestSort,
TestDistributedSearch, CustomCollectionTest, HdfsSyncSliceTest,
HdfsWriteToMultipleCollectionsTest, TestReloadAndDeleteDocs,
TestSchemaVersionResource, OpenCloseCoreStressTest, OverseerTest,
TestReplicationHandler, TestZkChroot, TestDistributedGrouping,
DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest,
TestStressReorder, TestRangeQuery, StatsComponentTest, TestIndexSearcher,
SimplePostToolTest, SuggesterFSTTest, CoreAdminHandlerTest, SuggesterTSTTest,
SuggesterWFSTTest, PolyFieldTest, TestAtomicUpdateErrorCases,
DefaultValueUpdateProcessorTest, DistanceFunctionTest, SolrInfoMBeanTest,
CacheHeaderTest, TestSurroundQueryParser, TestQueryUtils,
DirectSolrSpellCheckerTest, TestQueryTypes, FileBasedSpellCheckerTest,
XmlUpdateRequestHandlerTest, RequiredFieldsTest, TestArbitraryIndexDir,
IndexSchemaTest, UpdateRequestProcessorFactoryTest, JSONWriterTest,
TestAnalyzedSuggestions, CSVRequestHandlerTest, SearchHandlerTest,
TestLFUCache, AlternateDirectoryTest, ResponseLogComponentTest, SampleTest,
TestElisionMultitermQuery, OutputWriterTest, TestPostingsSolrHighlighter,
TestXIncludeConfig, TestIBSimilarityFactory, TestJmxMonitoredMap,
OpenExchangeRatesOrgProviderTest, DateMathParserTest,
TestSuggestSpellingConverter, DOMUtilTest, RAMDirectoryFactoryTest,
ClusterStateTest, TestDocumentBuilder, DistributedMLTComponentTest,
TestHighlightDedupGrouping, TestTolerantSearch,
TestEmbeddedSolrServerConstructors, AsyncMigrateRouteKeyTest, DeleteShardTest,
DistribJoinFromCollectionTest, LeaderInitiatedRecoveryOnCommitTest,
MultiThreadedOCPTest, OverseerRolesTest, SharedFSAutoReplicaFailoverTest,
SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest,
SolrXmlInZkTest, TestCloudPivotFacet, TestCollectionAPI, TestCryptoKeys,
TestRequestStatusCollectionAPI, HdfsCollectionsAPIDistributedZkTest,
HdfsThreadLeakTest, HdfsUnloadDistributedZkTest, StressHdfsTest,
TestClusterStateMutator, ZkStateWriterTest, HdfsDirectoryFactoryTest,
TestConfigOverlay, TestConfigSets, TestInitParams,
TestReplicationHandlerBackup, TestRestoreCore]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRestoreCore
-Dtests.seed=1B1B00EF9526E2B9 -Dtests.slow=true -Dtests.locale=hi_IN
-Dtests.timezone=America/Guayaquil -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | TestRestoreCore (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the
following files (in the order of attempts):
[junit4] >
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001: java.nio.file.DirectoryNotEmptyException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001
[junit4] >
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001\snapshot.jh:
java.nio.file.AccessDeniedException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001\snapshot.jh
[junit4] >
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001: java.nio.file.DirectoryNotEmptyException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001\tempDir-001
[junit4] >
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001: java.nio.file.DirectoryNotEmptyException:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestRestoreCore
1B1B00EF9526E2B9-001
[junit4] > at
__randomizedtesting.SeedInfo.seed([1B1B00EF9526E2B9]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:286)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed on J0 in 6.74s, 2 tests, 1 error <<< FAILURES!
[...truncated 198 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:519: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:191:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:510:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1351:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:958:
There were test failures: 481 suites, 1904 tests, 1 suite-level error, 57
ignored (29 assumptions)
Total time: 55 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_40
-XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]