Build: http://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/5820/ Java: 32bit/jdk1.8.0_92 -client -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart Error Message: IOException occured when talking to server at: http://127.0.0.1:51195/solr/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:51195/solr/collection1 at __randomizedtesting.SeedInfo.seed([B5F54CB6F816554A:6D02885253CD9716]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:620) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:152) at org.apache.solr.handler.TestReplicationHandler.index(TestReplicationHandler.java:176) at org.apache.solr.handler.TestReplicationHandler.doTestIndexFetchOnMasterRestart(TestReplicationHandler.java:609) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Caused by: java.net.SocketException: Software caused connection abort: recv failed at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:139) at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:155) at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:284) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:165) at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:167) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:511) ... 47 more Build Log: [...truncated 10948 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\init-core-data-001 [junit4] 2> 755287 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits [junit4] 2> 755288 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\collection1 [junit4] 2> 755302 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 755304 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3cd7ce{/solr,null,AVAILABLE} [junit4] 2> 755307 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.e.j.s.ServerConnector Started ServerConnector@19b85bf{HTTP/1.1,[http/1.1]}{127.0.0.1:51040} [junit4] 2> 755307 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.e.j.s.Server Started @759170ms [junit4] 2> 755307 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=51040} [junit4] 2> 755307 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93 [junit4] 2> 755308 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001' [junit4] 2> 755308 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 755308 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 755309 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\solr.xml [junit4] 2> 755323 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\. [junit4] 2> 755323 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreContainer New CoreContainer 19335512 [junit4] 2> 755323 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001] [junit4] 2> 755323 WARN (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\lib [junit4] 2> 755324 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false, [junit4] 2> 755330 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: [junit4] 2> 755332 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 755332 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 755332 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 755332 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 755336 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\. [junit4] 2> 755340 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data\} [junit4] 2> 755340 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\.\collection1 [junit4] 2> 755341 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 755341 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0 [junit4] 2> 755341 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 755342 INFO (coreLoadExecutor-1661-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\collection1' [junit4] 2> 755343 INFO (coreLoadExecutor-1661-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 755343 INFO (coreLoadExecutor-1661-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 755384 INFO (coreLoadExecutor-1661-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 755388 INFO (coreLoadExecutor-1661-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 755394 INFO (coreLoadExecutor-1661-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 755399 INFO (coreLoadExecutor-1661-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 755406 INFO (coreLoadExecutor-1661-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 755417 INFO (coreLoadExecutor-1661-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\collection1' [junit4] 2> 755417 INFO (coreLoadExecutor-1661-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\.\collection1 [junit4] 2> 755417 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 755417 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\.\collection1\data\] [junit4] 2> 755417 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 755417 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\.\collection1\data\ [junit4] 2> 755417 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\.\collection1\data\index/ [junit4] 2> 755417 WARN (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\.\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 755419 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\.\collection1\data\index [junit4] 2> 755419 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=6.11328125, floorSegmentMB=0.6025390625, forceMergeDeletesPctAllowed=1.8275865179344652, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19035061678183463 [junit4] 2> 755419 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_1,generation=1} [junit4] 2> 755419 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 755422 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 755425 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\.\collection1\data\ [junit4] 2> 755434 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 755435 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 755437 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 755438 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 755440 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy [junit4] 2> 755441 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file [junit4] 2> 755441 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 755443 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 755443 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 755443 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 755443 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_1,generation=1} [junit4] 2> 755444 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 755444 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@848aaf[collection1] main] [junit4] 2> 755444 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\collection1\conf [junit4] 2> 755444 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\collection1\conf} [junit4] 2> 755444 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-001\collection1\conf [junit4] 2> 755445 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 755445 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 755445 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 755445 INFO (coreLoadExecutor-1661-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 755446 INFO (searcherExecutor-1662-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@848aaf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 755845 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\collection1 [junit4] 2> 755859 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 755860 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e0f2a{/solr,null,AVAILABLE} [junit4] 2> 755862 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.e.j.s.ServerConnector Started ServerConnector@33875e{HTTP/1.1,[http/1.1]}{127.0.0.1:51043} [junit4] 2> 755862 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.e.j.s.Server Started @759724ms [junit4] 2> 755862 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=51043} [junit4] 2> 755864 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1d16e93 [junit4] 2> 755864 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002' [junit4] 2> 755864 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 755867 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 755867 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\solr.xml [junit4] 2> 755874 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\. [junit4] 2> 755875 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreContainer New CoreContainer 11452301 [junit4] 2> 755875 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002] [junit4] 2> 755875 WARN (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreContainer Couldn't add files from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\lib to classpath: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\lib [junit4] 2> 755876 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false, [junit4] 2> 755879 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: [junit4] 2> 755881 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 755881 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 755882 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 755882 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 755883 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\. [junit4] 2> 755892 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data\} [junit4] 2> 755892 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1 [junit4] 2> 755893 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 755894 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0 [junit4] 2> 755894 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 755895 INFO (coreLoadExecutor-1671-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\collection1' [junit4] 2> 755895 INFO (coreLoadExecutor-1671-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 755895 INFO (coreLoadExecutor-1671-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 755915 INFO (coreLoadExecutor-1671-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 755917 INFO (coreLoadExecutor-1671-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 755924 INFO (coreLoadExecutor-1671-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 755928 INFO (coreLoadExecutor-1671-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 755931 INFO (coreLoadExecutor-1671-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 755935 INFO (coreLoadExecutor-1671-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\collection1' [junit4] 2> 755935 INFO (coreLoadExecutor-1671-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1 [junit4] 2> 755935 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 755935 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\] [junit4] 2> 755936 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1 [junit4] 2> 755936 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\ [junit4] 2> 755936 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index/ [junit4] 2> 755936 WARN (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 755937 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index [junit4] 2> 755937 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=40, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=6.11328125, floorSegmentMB=0.6025390625, forceMergeDeletesPctAllowed=1.8275865179344652, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.19035061678183463 [junit4] 2> 755937 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_1,generation=1} [junit4] 2> 755938 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 755939 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 755941 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 755943 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\ [junit4] 2> 755944 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 755947 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 755950 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 755952 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy [junit4] 2> 755952 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file [junit4] 2> 755952 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 755953 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 755953 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 755953 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 755954 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_1,generation=1} [junit4] 2> 755954 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 755954 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5980b5[collection1] main] [junit4] 2> 755954 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\collection1\conf [junit4] 2> 755954 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\collection1\conf} [junit4] 2> 755954 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\collection1\conf [junit4] 2> 755956 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 755958 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 755959 INFO (coreLoadExecutor-1671-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 755959 INFO (searcherExecutor-1672-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5980b5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 756407 INFO (qtp1657846-5163) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 756412 INFO (qtp1657846-5166) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 2 [junit4] 2> 756414 INFO (qtp1657846-5167) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 756421 INFO (qtp1657846-5167) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_2,generation=2} [junit4] 2> 756421 INFO (qtp1657846-5167) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2 [junit4] 2> 756425 INFO (qtp1657846-5167) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@122bc0f[collection1] main] [junit4] 2> 756426 INFO (qtp1657846-5167) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 756426 INFO (searcherExecutor-1662-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@122bc0f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))} [junit4] 2> 756427 INFO (qtp1657846-5167) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 13 [junit4] 2> 756455 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 756460 INFO (qtp1657846-5169) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 756461 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 756461 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 756461 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 756490 INFO (qtp1657846-5170) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=28 [junit4] 2> 756491 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4 [junit4] 2> 756493 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064706925 [junit4] 2> 756494 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 756497 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=32.7490234375, floorSegmentMB=1.5126953125, forceMergeDeletesPctAllowed=1.8341148040372046, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 756497 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_1,generation=1} [junit4] 2> 756498 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 756498 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 756499 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_1,generation=1} [junit4] 2> 756499 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 756499 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 756499 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@fe1df7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@901fde) [junit4] 2> 756500 INFO (qtp1657846-5165) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 756503 INFO (qtp1657846-5165) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 756505 INFO (qtp1657846-5163) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 756508 INFO (qtp1657846-5166) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 756509 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1847) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@fe1df7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@901fde) [junit4] 2> 756514 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.21270996579556575] [junit4] 2> 756515 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> 756515 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2 [junit4] 2> 756515 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 756516 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1046fa3[collection1] main] [junit4] 2> 756517 INFO (searcherExecutor-1672-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1046fa3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))} [junit4] 2> 756517 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064706925 [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064706925;done=true>>] [junit4] 2> 756517 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=false [junit4] 2> 756517 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064706925 [junit4] 2> 756518 INFO (qtp8806956-5182) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:51040/solr/collection1/replication&command=fetchindex} status=0 QTime=63 [junit4] 2> 756529 INFO (qtp8806956-5189) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 756530 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs [junit4] 2> 756531 INFO (qtp1657846-5167) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 756536 INFO (qtp1657846-5168) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3 [junit4] 2> 756540 INFO (qtp1657846-5169) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 756540 INFO (qtp8806956-5188) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=3 [junit4] 2> 756542 INFO (qtp1657846-5170) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 756543 INFO (qtp8806956-5187) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 756544 INFO (qtp1657846-5165) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 756545 INFO (qtp1657846-5165) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. [junit4] 2> 756546 INFO (qtp1657846-5165) [ x:collection1] o.a.s.c.SolrCore SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 756546 INFO (qtp1657846-5165) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 756547 INFO (qtp1657846-5165) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 2 [junit4] 2> 756550 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 756552 INFO (qtp1657846-5163) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 756553 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master. [junit4] 2> 756553 INFO (qtp8806956-5186) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:51040/solr/collection1/replication&command=fetchindex} status=0 QTime=4 [junit4] 2> 756556 INFO (qtp1657846-5166) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 756557 INFO (qtp1657846-5167) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 756560 INFO (qtp1657846-5167) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_3,generation=3} [junit4] 2> 756561 INFO (qtp1657846-5167) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 756561 INFO (qtp1657846-5167) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a18042[collection1] main] [junit4] 2> 756561 INFO (qtp1657846-5167) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 756561 INFO (searcherExecutor-1662-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a18042[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1)))} [junit4] 2> 756562 INFO (qtp1657846-5167) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 4 [junit4] 2> 756564 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 756566 INFO (qtp1657846-5168) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 756567 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 756567 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 756567 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 756569 INFO (qtp1657846-5168) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1 [junit4] 2> 756571 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7 [junit4] 2> 756571 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064707005 [junit4] 2> 756572 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 756573 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=32.7490234375, floorSegmentMB=1.5126953125, forceMergeDeletesPctAllowed=1.8341148040372046, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 756575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> 756575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2 [junit4] 2> 756575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 756575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> 756575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2 [junit4] 2> 756575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 756575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@7af119 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@155cca9) [junit4] 2> 756575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists [junit4] 2> 756575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 756575 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists [junit4] 2> 756577 INFO (qtp1657846-5170) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 756579 INFO (qtp1657846-5170) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 756581 INFO (qtp1657846-5165) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 756584 INFO (qtp1657846-5163) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 756585 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1928) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@7af119 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@155cca9) [junit4] 2> 756592 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.21270996579556575] [junit4] 2> 756597 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 756597 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 756597 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 756716 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@15214d0[collection1] main] [junit4] 2> 756717 INFO (searcherExecutor-1672-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@15214d0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1)))} [junit4] 2> 756718 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064707005 [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064707005;done=true>>] [junit4] 2> 756719 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=false [junit4] 2> 756719 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064707005 [junit4] 2> 756719 INFO (qtp8806956-5185) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:51040/solr/collection1/replication&command=fetchindex} status=0 QTime=155 [junit4] 2> 756722 INFO (qtp8806956-5184) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=1 [junit4] 2> 756723 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs [junit4] 2> 756724 INFO (qtp1657846-5166) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 756725 INFO (qtp1657846-5167) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 756730 INFO (qtp1657846-5169) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0 [junit4] 2> 756730 INFO (qtp8806956-5193) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2 [junit4] 2> 756732 INFO (qtp1657846-5168) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 756732 INFO (qtp1657846-5170) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 756732 INFO (qtp8806956-5191) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 756733 INFO (indexFetcher-1676-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master. [junit4] 2> 756736 INFO (qtp1657846-5165) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 2 [junit4] 2> 756737 INFO (qtp1657846-5163) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 756743 INFO (qtp1657846-5163) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_3,generation=3} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_4,generation=4} [junit4] 2> 756743 INFO (qtp1657846-5163) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 4 [junit4] 2> 756743 INFO (qtp1657846-5163) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13b315a[collection1] realtime] [junit4] 2> 756744 INFO (qtp1657846-5163) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 756744 INFO (qtp1657846-5163) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 6 [junit4] 2> 756747 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 756749 INFO (qtp1657846-5166) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 756750 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4 [junit4] 2> 756750 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3 [junit4] 2> 756750 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 756752 INFO (qtp1657846-5167) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=1 [junit4] 2> 756753 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 756753 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064707187 [junit4] 2> 756753 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 756755 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=32.7490234375, floorSegmentMB=1.5126953125, forceMergeDeletesPctAllowed=1.8341148040372046, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 756756 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 756756 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 756757 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 756757 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 756757 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 756757 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 756757 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 756757 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 756757 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 757757 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 757757 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 757757 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 757757 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 758762 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 758762 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 758762 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 758762 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 759762 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 759762 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 759762 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 759762 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 760773 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 760773 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 760773 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 760773 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 761773 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 761773 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 761773 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 761773 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 762774 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 762774 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 762775 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 762775 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 763776 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 763778 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 763778 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 763778 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 764784 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 764788 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 764788 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 764788 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 765789 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 765790 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 765790 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 765790 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 766790 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2 [junit4] 2> 766790 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> 766790 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3 [junit4] 2> 766790 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able [junit4] 2> 767819 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index files to be delete-able [junit4] 2> 767819 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1 [junit4] 2> 767820 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@3dbdb4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19c0e55) [junit4] 2> 767820 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists [junit4] 2> 767820 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 767820 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists [junit4] 2> 767820 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists [junit4] 2> 767825 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists [junit4] 2> 767825 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists [junit4] 2> 767827 INFO (qtp1657846-5169) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 767832 INFO (qtp1657846-5168) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 767834 INFO (qtp1657846-5168) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 767835 INFO (qtp1657846-5165) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 767837 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2009) : 11 secs (182 bytes/sec) to MockDirectoryWrapper(RAMDirectory@3dbdb4 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19c0e55) [junit4] 2> 767841 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.21270996579556575] [junit4] 2> 767870 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_3,generation=3} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@cfaa50 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b78760),segFN=segments_4,generation=4} [junit4] 2> 767870 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 4 [junit4] 2> 767874 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 767880 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b394fb[collection1] main] [junit4] 2> 767880 INFO (searcherExecutor-1672-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1b394fb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)))} [junit4] 2> 767880 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064707187 [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064707187;done=true>>] [junit4] 2> 767880 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=false [junit4] 2> 767880 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-002\.\collection1\data\index.20160506064707187 [junit4] 2> 767880 INFO (qtp8806956-5182) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:51040/solr/collection1/replication&command=fetchindex} status=0 QTime=11134 [junit4] 2> 767884 INFO (qtp1657846-5163) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0 [junit4] 2> 767885 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs [junit4] 2> 767887 INFO (qtp8806956-5189) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 767887 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[B5F54CB6F816554A]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs [junit4] 2> 767897 INFO (qtp1657846-5166) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 8 [junit4] 2> 767898 INFO (qtp1657846-5166) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 767922 INFO (qtp1657846-5166) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=4 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_3,generation=3} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_4,generation=4} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@a21b34 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18e0edb),segFN=segments_5,generation=5} [junit4] 2> 767922 INFO (qtp1657846-5166) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 5 [junit4] 2> 767922 INFO (qtp1657846-5166) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@485c2[collection1] main] [junit4] 2> 767923 INFO (searcherExecutor-1662-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collect [...truncated too long message...] rently being tracked [junit4] 2> 818816 INFO (coreCloseExecutor-2336-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-031\.\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-031\.\collection1\data\;done=false>>] [junit4] 2> 818816 INFO (coreCloseExecutor-2336-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true [junit4] 2> 818816 INFO (coreCloseExecutor-2336-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-031\.\collection1\data\ [junit4] 2> 818816 INFO (coreCloseExecutor-2336-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-031\.\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-031\.\collection1\data\index;done=false>>] [junit4] 2> 818816 INFO (coreCloseExecutor-2336-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true [junit4] 2> 818816 INFO (coreCloseExecutor-2336-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-031\.\collection1\data\index [junit4] 2> 818821 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[B5F54CB6F816554A]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@95d151{/solr,null,UNAVAILABLE} [junit4] 2> 818832 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[B5F54CB6F816554A]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1621611{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 818832 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[B5F54CB6F816554A]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=17995372 [junit4] 2> 818836 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@cb84aa [junit4] 2> 818836 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=4,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=12,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0} [junit4] 2> 818836 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState [junit4] 2> 818836 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter [junit4] 2> 818836 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser [junit4] 2> 818839 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request. [junit4] 2> 818840 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked [junit4] 2> 818840 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-032\.\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-032\.\collection1\data\;done=false>>] [junit4] 2> 818841 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true [junit4] 2> 818841 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-032\.\collection1\data\ [junit4] 2> 818841 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-032\.\collection1\data\index.20160506064809183 [CachedDir<<refCount=0;path=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-032\.\collection1\data\index.20160506064809183;done=false>>] [junit4] 2> 818841 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true [junit4] 2> 818841 INFO (coreCloseExecutor-2338-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001\solr-instance-032\.\collection1\data\index.20160506064809183 [junit4] 2> 818842 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[B5F54CB6F816554A]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b16bb7{/solr,null,UNAVAILABLE} [junit4] 2> 818874 INFO (SUITE-TestReplicationHandler-seed#[B5F54CB6F816554A]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_B5F54CB6F816554A-001 [junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=2, maxDocsPerChunk=581, blockSize=4), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=2, blockSize=4)), sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=de-DE, timezone=Pacific/Ponape [junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_92 (32-bit)/cpus=3,threads=1,free=323266328,total=389021696 [junit4] 2> NOTE: All tests run in this JVM: [TestUniqueKeyFieldResource, ChaosMonkeyNothingIsSafeTest, TestGroupingSearch, CloudExitableDirectoryReaderTest, QueryEqualityTest, TestSchemaResource, AsyncMigrateRouteKeyTest, LeaderElectionTest, TestStressVersions, DistribJoinFromCollectionTest, SimpleCollectionCreateDeleteTest, TestEmbeddedSolrServerConstructors, TestInfoStreamLogging, CdcrRequestHandlerTest, TestOverriddenPrefixQueryForCustomFieldType, TestQuerySenderNoQuery, TestHashPartitioner, TestTolerantUpdateProcessorRandomCloud, TestSolrQueryResponse, TestReqParamsAPI, SaslZkACLProviderTest, TestSchemaManager, TestWordDelimiterFilterFactory, TestFileDictionaryLookup, TestCustomDocTransformer, TestManagedSynonymFilterFactory, AddBlockUpdateTest, TestDynamicFieldResource, ReplicationFactorTest, TestAnalyzedSuggestions, PolyFieldTest, RequiredFieldsTest, BigEndianAscendingWordDeserializerTest, TestConfigSetProperties, HdfsChaosMonkeyNothingIsSafeTest, HdfsBasicDistributedZk2Test, TestFaceting, OverseerTest, XsltUpdateRequestHandlerTest, StandardRequestHandlerTest, CollectionsAPIAsyncDistributedZkTest, TestRuleBasedAuthorizationPlugin, TestGraphTermsQParserPlugin, MoreLikeThisHandlerTest, OutOfBoxZkACLAndCredentialsProvidersTest, RulesTest, ShardRoutingTest, TestLMDirichletSimilarityFactory, PeerSyncTest, CircularListTest, TestReplicationHandler] [junit4] Completed [134/593 (1!)] on J0 in 63.66s, 15 tests, 1 error <<< FAILURES! [...truncated 1503 lines...] BUILD FAILED C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:740: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:684: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\build.xml:59: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build.xml:233: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\common-build.xml:529: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1457: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1014: There were test failures: 593 suites (10 ignored), 2396 tests, 1 error, 124 ignored (80 assumptions) [seed: B5F54CB6F816554A] Total time: 78 minutes 20 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org