Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Solaris/261/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
4 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestCoreDiscovery
Error Message:
ERROR: SolrIndexSearcher opens=27 closes=26
Stack Trace:
java.lang.AssertionError: ERROR: SolrIndexSearcher opens=27 closes=26
at __randomizedtesting.SeedInfo.seed([45F43F257D825341]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:453)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:225)
at sun.reflect.GeneratedMethodAccessor19.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
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:46)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
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:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestCoreDiscovery
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.core.TestCoreDiscovery:
1) Thread[id=14668, name=searcherExecutor-6412-thread-1, state=WAITING,
group=TGRP-TestCoreDiscovery] at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.core.TestCoreDiscovery:
1) Thread[id=14668, name=searcherExecutor-6412-thread-1, state=WAITING,
group=TGRP-TestCoreDiscovery]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([45F43F257D825341]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.core.TestCoreDiscovery
Error Message:
There are still zombie threads that couldn't be terminated: 1)
Thread[id=14668, name=searcherExecutor-6412-thread-1, state=WAITING,
group=TGRP-TestCoreDiscovery] at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
1) Thread[id=14668, name=searcherExecutor-6412-thread-1, state=WAITING,
group=TGRP-TestCoreDiscovery]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([45F43F257D825341]:0)
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Tue Dec 15 18:41:37
MSK 2015
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to
exceed: Tue Dec 15 18:41:37 MSK 2015
at
__randomizedtesting.SeedInfo.seed([45F43F257D825341:9E5F3FE378AA3AF2]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1419)
at
org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:771)
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:497)
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:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:46)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
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:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10215 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/init-core-data-001
[junit4] 2> 1864473 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits
[junit4] 2> 1864474 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/collection1
[junit4] 2> 1864509 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.e.j.s.Server jetty-9.3.6.v20151106
[junit4] 2> 1864510 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@774784f0{/solr,null,AVAILABLE}
[junit4] 2> 1864529 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@35cee0d2{HTTP/1.1,[http/1.1]}{127.0.0.1:49643}
[junit4] 2> 1864529 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.e.j.s.Server Started @1872509ms
[junit4] 2> 1864529 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/collection1/data,
hostContext=/solr, hostPort=49643}
[junit4] 2> 1864529 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@659e0bfd
[junit4] 2> 1864529 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001'
[junit4] 2> 1864530 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1864530 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1864530 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/solr.xml
[junit4] 2> 1864543 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/.
[junit4] 2> 1864543 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CoreContainer New CoreContainer 814676950
[junit4] 2> 1864543 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001]
[junit4] 2> 1864543 WARN
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/lib
to classpath:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/lib
[junit4] 2> 1864544 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
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,useRetries : false,
[junit4] 2> 1864545 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1864545 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1864545 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1864546 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for
authorization module.
[junit4] 2> 1864546 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1864546 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CorePropertiesLocator Looking for core definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/.
[junit4] 2> 1864547 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
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> 1864548 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CorePropertiesLocator Found core collection1 in
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/./collection1
[junit4] 2> 1864548 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1864549 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.s.SolrDispatchFilter
user.dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1
[junit4] 2> 1864549 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1864550 INFO (coreLoadExecutor-5255-thread-1) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/collection1'
[junit4] 2> 1864550 INFO (coreLoadExecutor-5255-thread-1) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1864550 INFO (coreLoadExecutor-5255-thread-1) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1864557 INFO (coreLoadExecutor-5255-thread-1) [ ]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1864559 INFO (coreLoadExecutor-5255-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1864563 INFO (coreLoadExecutor-5255-thread-1) [ ]
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1864566 INFO (coreLoadExecutor-5255-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1864568 INFO (coreLoadExecutor-5255-thread-1) [ ]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1864569 INFO (coreLoadExecutor-5255-thread-1) [ ]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/collection1'
[junit4] 2> 1864570 INFO (coreLoadExecutor-5255-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
instancedir
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/./collection1
[junit4] 2> 1864570 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1864570 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1864570 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core:
collection1
[junit4] 2> 1864570 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/./collection1/data
[junit4] 2> 1864570 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null
new=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 1864570 WARN (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory
'/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/./collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 1864570 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/./collection1/data/index
[junit4] 2> 1864571 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=22, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=35.31640625,
floorSegmentMB=1.3564453125, forceMergeDeletesPctAllowed=11.3188745513442,
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1864571 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits:
num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68dd2dcc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5bd9491),segFN=segments_1,generation=1}
[junit4] 2> 1864571 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1864572 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1]
o.a.s.c.SolrCore Looking for old index directories to cleanup for core
collection1 in
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/./collection1/data/
[junit4] 2> 1864595 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as
default, creating implicit default
[junit4] 2> 1864598 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1864605 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1864605 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1864606 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1864608 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with
startup=lazy
[junit4] 2> 1864608 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1864608 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.SolrCore Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1864609 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1864609 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1864609 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=613440346,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1864609 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits:
num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68dd2dcc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5bd9491),segFN=segments_1,generation=1}
[junit4] 2> 1864609 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1864609 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@63728d0[collection1]
main
[junit4] 2> 1864610 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to
use dir:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/collection1/conf
[junit4] 2> 1864610 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs:
{storageDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/collection1/conf}
[junit4] 2> 1864610 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
file:dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-001/collection1/conf
[junit4] 2> 1864610 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1864610 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following
config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1864610 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1864610 INFO (coreLoadExecutor-5255-thread-1) [
x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1864610 INFO
(searcherExecutor-5256-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@63728d0[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1865054 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/collection1
[junit4] 2> 1865057 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.e.j.s.Server jetty-9.3.6.v20151106
[junit4] 2> 1865061 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@f484285{/solr,null,AVAILABLE}
[junit4] 2> 1865062 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.e.j.s.ServerConnector Started
ServerConnector@7c54739e{HTTP/1.1,[http/1.1]}{127.0.0.1:51195}
[junit4] 2> 1865062 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.e.j.s.Server Started @1873042ms
[junit4] 2> 1865062 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/collection1/data,
hostContext=/solr, hostPort=51195}
[junit4] 2> 1865062 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@659e0bfd
[junit4] 2> 1865062 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002'
[junit4] 2> 1865062 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1865062 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1865063 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/solr.xml
[junit4] 2> 1865066 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/.
[junit4] 2> 1865066 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CoreContainer New CoreContainer 1163977227
[junit4] 2> 1865066 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002]
[junit4] 2> 1865066 WARN
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/lib
to classpath:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/lib
[junit4] 2> 1865066 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
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,useRetries : false,
[junit4] 2> 1865067 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1865068 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1865068 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1865068 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for
authorization module.
[junit4] 2> 1865068 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1865069 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CorePropertiesLocator Looking for core definitions underneath
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/.
[junit4] 2> 1865070 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
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> 1865070 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CorePropertiesLocator Found core collection1 in
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1
[junit4] 2> 1865070 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1865071 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.s.SolrDispatchFilter
user.dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1
[junit4] 2> 1865071 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1865073 INFO (coreLoadExecutor-5265-thread-1) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/collection1'
[junit4] 2> 1865074 INFO (coreLoadExecutor-5265-thread-1) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1865074 INFO (coreLoadExecutor-5265-thread-1) [ ]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 1865078 INFO (coreLoadExecutor-5265-thread-1) [ ]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1865080 INFO (coreLoadExecutor-5265-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1865084 INFO (coreLoadExecutor-5265-thread-1) [ ]
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1865086 INFO (coreLoadExecutor-5265-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1865088 INFO (coreLoadExecutor-5265-thread-1) [ ]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1865089 INFO (coreLoadExecutor-5265-thread-1) [ ]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/collection1'
[junit4] 2> 1865089 INFO (coreLoadExecutor-5265-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
instancedir
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1
[junit4] 2> 1865089 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1865089 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/collection1],
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/]
[junit4] 2> 1865089 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core:
collection1
[junit4] 2> 1865090 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data
[junit4] 2> 1865090 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null
new=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 1865090 WARN (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory
'/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 1865090 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index
[junit4] 2> 1865090 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=22, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=35.31640625,
floorSegmentMB=1.3564453125, forceMergeDeletesPctAllowed=11.3188745513442,
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1865091 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits:
num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cf8626a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7385f567),segFN=segments_1,generation=1}
[junit4] 2> 1865091 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1865091 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1]
o.a.s.c.SolrCore Looking for old index directories to cleanup for core
collection1 in
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/
[junit4] 2> 1865093 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as
default, creating implicit default
[junit4] 2> 1865094 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1865095 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1865096 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1865097 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1865098 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with
startup=lazy
[junit4] 2> 1865098 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,lazy,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,defaults,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1865098 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.SolrCore Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1865098 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1865098 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1865099 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=613440346,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 1865099 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits:
num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cf8626a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7385f567),segFN=segments_1,generation=1}
[junit4] 2> 1865100 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1865100 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3d079b9e[collection1]
main
[junit4] 2> 1865100 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to
use dir:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/collection1/conf
[junit4] 2> 1865100 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs:
{storageDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/collection1/conf}
[junit4] 2> 1865100 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
file:dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/collection1/conf
[junit4] 2> 1865100 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1865101 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of
1000ms
[junit4] 2> 1865101 INFO (coreLoadExecutor-5265-thread-1) [
x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1865102 INFO
(searcherExecutor-5266-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3d079b9e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1865576 INFO (qtp1300959840-12694) [ 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> 1865579 INFO (qtp1300959840-12695) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1865581 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1865583 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68dd2dcc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5bd9491),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68dd2dcc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5bd9491),segFN=segments_2,generation=2}
[junit4] 2> 1865583 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 1865585 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening Searcher@48a44ca7[collection1] main
[junit4] 2> 1865585 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1865585 INFO
(searcherExecutor-5256-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@48a44ca7[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)))}
[junit4] 2> 1865585 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1865594 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 1865596 INFO (qtp1300959840-12698) [ 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> 1865596 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1865597 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1865597 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1865598 WARN (qtp1300959840-12692) [ x:collection1]
o.a.s.h.ReplicationHandler Could not read checksum from index file:
_0_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 1865601 INFO (qtp1300959840-12692) [ 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=3
[junit4] 2> 1865602 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 11
[junit4] 2> 1865602 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index.20151215184131452
[junit4] 2> 1865602 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 1865602 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5999591403033244]
[junit4] 2> 1865603 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cf8626a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7385f567),segFN=segments_1,generation=1}
[junit4] 2> 1865604 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1865604 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1865604 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cf8626a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7385f567),segFN=segments_1,generation=1}
[junit4] 2> 1865604 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1865604 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 1865604 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to
MockDirectoryWrapper(RAMDirectory@7a19b45e
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11382706)
[junit4] 2> 1865606 INFO (qtp1300959840-12691) [ 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> 1865613 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0_MockRandom_0.tix&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865616 INFO (qtp1300959840-12695) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0_MockRandom_0.doc&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865619 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865621 INFO (qtp1300959840-12697) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0_MockRandom_0.tbk&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865623 INFO (qtp1300959840-12698) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865626 INFO (qtp1300959840-12692) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865629 INFO (qtp1300959840-12691) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865631 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865633 INFO (qtp1300959840-12695) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=2&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865635 INFO (qtp1300959840-12696) [ 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> 1865636 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Total time taken for download
(fullCopy=false,bytesDownloaded=1594) : 0 secs (null bytes/sec) to
MockDirectoryWrapper(RAMDirectory@7a19b45e
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11382706)
[junit4] 2> 1865638 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=23, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=31.6005859375,
floorSegmentMB=1.2138671875, forceMergeDeletesPctAllowed=6.390960140636153,
segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1865638 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cf8626a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7385f567),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cf8626a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7385f567),segFN=segments_2,generation=2}
[junit4] 2> 1865639 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 1865639 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1865639 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening Searcher@6075da2d[collection1] main
[junit4] 2> 1865640 INFO
(searcherExecutor-5266-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6075da2d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)))}
[junit4] 2> 1865640 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.CachingDirectoryFactory looking to close
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index.20151215184131452
[CachedDir<<refCount=0;path=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index.20151215184131452;done=true>>]
[junit4] 2> 1865640 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.CachingDirectoryFactory Closing directory:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index.20151215184131452
[junit4] 2> 1865640 INFO (qtp2125443004-12712) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:49643/solr/collection1/replication&command=fetchindex}
status=0 QTime=49
[junit4] 2> 1865646 INFO (qtp2125443004-12714) [ 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=3
[junit4] 2> 1865646 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 1865648 INFO (qtp1300959840-12697) [ 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> 1865651 INFO (qtp1300959840-12698) [ 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> 1865658 INFO (qtp1300959840-12692) [ 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> 1865659 INFO (qtp2125443004-12716) [ 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=5
[junit4] 2> 1865661 INFO (qtp1300959840-12691) [ 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> 1865664 INFO (qtp2125443004-12711) [ 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> 1865666 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1865666 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1865667 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.c.SolrCore SolrIndexSearcher has not changed - not re-opening:
org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 1865667 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1865667 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2}{commit=} 0 1
[junit4] 2> 1865669 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 1865671 INFO (qtp1300959840-12695) [ 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> 1865671 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 1865672 INFO (qtp2125443004-12715) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:49643/solr/collection1/replication&command=fetchindex}
status=0 QTime=4
[junit4] 2> 1865676 INFO (qtp1300959840-12695) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1865678 INFO (qtp1300959840-12697) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1865683 INFO (qtp1300959840-12698) [ 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> 1865684 INFO (indexFetcher-5270-thread-1) [ x:collection1]
o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 1865684 INFO (qtp1300959840-12697) [ x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68dd2dcc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5bd9491),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68dd2dcc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5bd9491),segFN=segments_3,generation=3}
[junit4] 2> 1865685 INFO (qtp1300959840-12697) [ x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 1865685 INFO (qtp1300959840-12697) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening Searcher@33d8f46d[collection1] main
[junit4] 2> 1865685 INFO (qtp1300959840-12697) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1865685 INFO
(searcherExecutor-5256-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@33d8f46d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)
Uninverting(_1(6.0.0):C1)))}
[junit4] 2> 1865685 INFO (qtp1300959840-12697) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={commit=true&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 1865687 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 1865690 INFO (qtp1300959840-12692) [ 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> 1865691 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 1865691 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 1865691 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1865693 WARN (qtp1300959840-12691) [ x:collection1]
o.a.s.h.ReplicationHandler Could not read checksum from index file:
_0_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 1865693 WARN (qtp1300959840-12691) [ x:collection1]
o.a.s.h.ReplicationHandler Could not read checksum from index file:
_1_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 1865693 INFO (qtp1300959840-12691) [ 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> 1865694 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 20
[junit4] 2> 1865695 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index.20151215184131545
[junit4] 2> 1865695 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 1865695 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy:
minMergeSize=1000, mergeFactor=13, maxMergeSize=9223372036854775807,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.5999591403033244]
[junit4] 2> 1865696 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cf8626a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7385f567),segFN=segments_2,generation=2}
[junit4] 2> 1865696 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 1865697 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1865697 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cf8626a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7385f567),segFN=segments_2,generation=2}
[junit4] 2> 1865697 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 1865697 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 1865697 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting download (fullCopy=false) to
MockDirectoryWrapper(RAMDirectory@6dda9a5e
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b2ad8bc)
[junit4] 2> 1865697 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 1865697 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0_MockRandom_0.tix because it
already exists
[junit4] 2> 1865697 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0_MockRandom_0.doc because it
already exists
[junit4] 2> 1865697 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists
[junit4] 2> 1865697 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0_MockRandom_0.tbk because it
already exists
[junit4] 2> 1865699 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_0_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865700 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists
[junit4] 2> 1865700 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists
[junit4] 2> 1865701 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists
[junit4] 2> 1865701 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists
[junit4] 2> 1865703 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1_MockRandom_0.doc&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865705 INFO (qtp1300959840-12695) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865709 INFO (qtp1300959840-12698) [ 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> 1865712 INFO (qtp1300959840-12697) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865715 INFO (qtp1300959840-12692) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865717 INFO (qtp1300959840-12691) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865719 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865722 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1_MockRandom_0.tfp&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865725 INFO (qtp1300959840-12695) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865729 INFO (qtp1300959840-12698) [ 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> 1865730 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Total time taken for download
(fullCopy=false,bytesDownloaded=1571) : 0 secs (null bytes/sec) to
MockDirectoryWrapper(RAMDirectory@6dda9a5e
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b2ad8bc)
[junit4] 2> 1865731 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Cannot complete replication attempt because file already
exists:_0_MockRandom_0.sd
[junit4] 2> 1865731 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=23, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=31.6005859375,
floorSegmentMB=1.2138671875, forceMergeDeletesPctAllowed=6.390960140636153,
segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1865731 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3cf8626a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7385f567),segFN=segments_2,generation=2}
[junit4] 2> 1865731 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 1865733 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1865733 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.CachingDirectoryFactory looking to close
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index.20151215184131545
[CachedDir<<refCount=0;path=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index.20151215184131545;done=true>>]
[junit4] 2> 1865733 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.CachingDirectoryFactory Closing directory:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index.20151215184131545
[junit4] 2> 1865733 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Replication attempt was not successful - trying a full
index replication reloadCore=false
[junit4] 2> 1865735 INFO (qtp1300959840-12697) [ 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> 1865735 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 1865735 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 1865735 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1865743 WARN (qtp1300959840-12692) [ x:collection1]
o.a.s.h.ReplicationHandler Could not read checksum from index file:
_0_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 1865744 WARN (qtp1300959840-12692) [ x:collection1]
o.a.s.h.ReplicationHandler Could not read checksum from index file:
_1_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 1865744 INFO (qtp1300959840-12692) [ 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> 1865745 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 20
[junit4] 2> 1865745 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index.20151215184131595
[junit4] 2> 1865745 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting download (fullCopy=true) to
MockDirectoryWrapper(RAMDirectory@658b3481
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c5308db)
[junit4] 2> 1865747 INFO (qtp1300959840-12691) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865750 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_0_MockRandom_0.tix&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865753 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_0_MockRandom_0.doc&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865757 INFO (qtp1300959840-12695) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_0.nvd&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865775 INFO (qtp1300959840-12698) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_0_MockRandom_0.tbk&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865778 INFO (qtp1300959840-12697) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_0_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865781 INFO (qtp1300959840-12692) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_0.fdx&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865784 INFO (qtp1300959840-12691) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_0.fdt&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865787 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_0.fnm&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865789 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_0.nvm&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865793 INFO (qtp1300959840-12695) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1_MockRandom_0.doc&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865796 INFO (qtp1300959840-12698) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1_MockRandom_0.sd&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865799 INFO (qtp1300959840-12697) [ 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> 1865803 INFO (qtp1300959840-12692) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.nvd&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865805 INFO (qtp1300959840-12691) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.fdx&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865808 INFO (qtp1300959840-12694) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.fnm&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865811 INFO (qtp1300959840-12696) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.nvm&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865814 INFO (qtp1300959840-12695) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1_MockRandom_0.tfp&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865816 INFO (qtp1300959840-12698) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={generation=3&qt=/replication&file=_1.fdt&checksum=true&wt=filestream&command=filecontent}
status=0 QTime=0
[junit4] 2> 1865819 INFO (qtp1300959840-12697) [ 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> 1865819 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Total time taken for download
(fullCopy=true,bytesDownloaded=2991) : 0 secs (null bytes/sec) to
MockDirectoryWrapper(RAMDirectory@658b3481
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c5308db)
[junit4] 2> 1865820 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher New index installed. Updating index properties...
index=index.20151215184131595
[junit4] 2> 1865820 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher removing old index directory
MockDirectoryWrapper(RAMDirectory@3cf8626a
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7385f567)
[junit4] 2> 1865820 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 1865821 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrCore New index directory detected:
old=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index/
new=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index.20151215184131595
[junit4] 2> 1865821 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=37, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=57.8359375,
floorSegmentMB=0.7958984375, forceMergeDeletesPctAllowed=10.603727199166821,
segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1865821 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@658b3481
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c5308db),segFN=segments_3,generation=3}
[junit4] 2> 1865821 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 1865821 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1865822 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening Searcher@7143a26e[collection1] main
[junit4] 2> 1865822 INFO
(searcherExecutor-5266-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@7143a26e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):C1)
Uninverting(_1(6.0.0):C1)))}
[junit4] 2> 1865823 INFO (qtp2125443004-12709) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/replication
params={wait=true&masterUrl=http://127.0.0.1:49643/solr/collection1/replication&command=fetchindex}
status=0 QTime=136
[junit4] 2> 1865823 INFO (qtp2125443004-12709) [ x:collection1]
o.a.s.c.CachingDirectoryFactory looking to close
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index
[CachedDir<<refCount=0;path=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index;done=true>>]
[junit4] 2> 1865823 INFO (qtp2125443004-12709) [ x:collection1]
o.a.s.c.CachingDirectoryFactory Closing directory:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandler_45F43F257D825341-001/solr-instance-002/./collection1/data/index
[junit4] 2> 1865825 INFO (qtp2125443004-12712) [ 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=0
[junit4] 2> 1865825 INFO
(TEST-TestReplicationHandler.testEmptyCommits-seed#[45F43F257D825341]) [ ]
o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 1865827 INFO (qtp1300959840-12692) [ 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> 1865830 INFO (qtp1300959840-12691) [ 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> 1865834 INFO (qtp1300959840-12694) [ 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> 1865835 INFO (qtp2125443004-12714) [ 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> 1865836 INFO (qtp1300959840-12696) [ 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> 1865838 INFO (qtp2125443004-12716) [ 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> 1865841 INFO (qtp1300959840-12695) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1865843 INFO (qtp1300959840-12698) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1865845 INFO (qtp1300959840-12698) [ x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=3
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68dd2dcc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5bd9491),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68dd2dcc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5bd9491),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@68dd2dcc
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5bd9491),segFN=segments_4,generation=4}
[junit4] 2> 1865845 INFO (qtp1300959840-12698) [ x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 4
[junit4] 2> 1865845 INFO (qtp1300959840-12698) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening Searcher@37f7c654[collection1] realtime
[junit4] 2> 1865845 INFO (qtp1300959840-12698) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1865845 INFO (qtp1300959840-12698) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 2
[junit4] 2> 1865847 WARN (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication
suffix
[junit4] 2> 1865850 INFO (qtp1300959840-12697) [ 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> 1865850 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Master's generation: 4
[junit4] 2> 1865850 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Slave's generation: 3
[junit4] 2> 1865850 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1865852 WARN (qtp1300959840-12697) [ x:collection1]
o.a.s.h.ReplicationHandler Could not read checksum from index file:
_0_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 1865852 WARN (qtp1300959840-12697) [ x:collection1]
o.a.s.h.ReplicationHandler Could not read checksum from index file:
_1_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 1865852 WARN (qtp1300959840-12697) [ x:collection1]
o.a.s.h.ReplicationHandler Could not read checksum from index file:
_2_MockRandom_0.sd
[junit4] 2> java.lang.ArrayIndexOutOfBoundsException
[junit4] 2> 1865853 INFO (qtp1300959840-12697) [ 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> 1865853 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.h.IndexFetcher Number of files in latest index in master: 30
[junit4] 2> 1865853 INFO (explicit-fetchindex-cmd) [ x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-
[...truncated too long message...]
ing.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=14668, name=searcherExecutor-6412-thread-1,
state=WAITING, group=TGRP-TestCoreDiscovery]
[junit4] 2> Dec 15, 2015 3:47:25 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be
terminated:
[junit4] 2> 1) Thread[id=14668, name=searcherExecutor-6412-thread-1,
state=WAITING, group=TGRP-TestCoreDiscovery]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {},
docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {},
locale=en_SG, timezone=America/Guadeloupe
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_66
(64-bit)/cpus=3,threads=2,free=154661776,total=407138304
[junit4] 2> NOTE: All tests run in this JVM: [TestDefaultStatsCache,
SegmentsInfoRequestHandlerTest, SuggestComponentTest,
TestBlendedInfixSuggestions, RequestHandlersTest, CachingDirectoryFactoryTest,
HighlighterConfigTest, DistributedQueueTest, BufferStoreTest,
DistribDocExpirationUpdateProcessorTest, SpatialHeatmapFacetsTest,
FileBasedSpellCheckerTest, TestFastWriter, SortByFunctionTest,
SimpleMLTQParserTest, TestCollationField, TestNonDefinedSimilarityFactory,
TestConfigSetProperties, TestCustomSort, OutputWriterTest,
TestDistribDocBasedVersion, TestJoin, SearchHandlerTest,
FullSolrCloudDistribCmdsTest, HighlighterMaxOffsetTest, SpellCheckCollatorTest,
TestScoreJoinQPNoScore, TestSolrQueryResponse,
TermVectorComponentDistributedTest, TermVectorComponentTest,
TestRandomFaceting, TestRTimerTree, SpellCheckComponentTest,
TestSolrQueryParser, TestFoldingMultitermQuery, TestLRUStatsCache,
CopyFieldTest, TestJsonFacets, TestPerFieldSimilarityWithDefaultOverride,
SolrInfoMBeanTest, FullHLLTest, StatsComponentTest,
HdfsCollectionsAPIDistributedZkTest, TestFiltering, DirectSolrConnectionTest,
SSLMigrationTest, TestUtils, TestReRankQParserPlugin,
TestLMJelinekMercerSimilarityFactory, TestNRTOpen,
CollectionsAPIDistributedZkTest, LeaderFailoverAfterPartitionTest,
TestRawResponseWriter, TestReload, RequestLoggingTest,
BinaryUpdateRequestHandlerTest, TestRestManager,
DistributedIntervalFacetingTest, MultiTermTest, TestValueSourceCache,
TestCrossCoreJoin, ResponseLogComponentTest, SolrIndexSplitterTest,
TestConfigSetsAPI, CurrencyFieldOpenExchangeTest, RestartWhileUpdatingTest,
TestSQLHandler, AtomicUpdatesTest, TestInfoStreamLogging,
LeaderInitiatedRecoveryOnShardRestartTest, TestIndexingPerformance,
RuleEngineTest, HdfsChaosMonkeyNothingIsSafeTest,
TestSolrQueryParserDefaultOperatorResource,
CollectionsAPIAsyncDistributedZkTest, TestMiniSolrCloudClusterBase,
InfoHandlerTest, TestSolrCoreProperties, TestRebalanceLeaders,
NotRequiredUniqueKeyTest, ZkStateReaderTest, TestQueryUtils,
TestSimpleTrackingShardHandler, OverseerRolesTest, SolrCmdDistributorTest,
TestSchemaResource, NoCacheHeaderTest, EchoParamsTest, DeleteShardTest,
SolrCloudExampleTest, AsyncMigrateRouteKeyTest, TestChildDocTransformer,
OverriddenZkACLAndCredentialsProvidersTest, TestDefaultSearchFieldResource,
HdfsSyncSliceTest, OverseerTaskQueueTest, TestRTGBase, TestShardHandlerFactory,
QueryParsingTest, BJQParserTest, HdfsUnloadDistributedZkTest,
TestSystemIdResolver, BlockCacheTest, TestStressRecovery, CacheHeaderTest,
CdcrReplicationDistributedZkTest, TestPHPSerializedResponseWriter,
TestCharFilters, ShowFileRequestHandlerTest, TestClassNameShortening,
TestRandomMergePolicy, TestAuthenticationFramework, TestRequestForwarding,
TestManagedResource, TestConfigReload, HdfsLockFactoryTest,
TestExceedMaxTermLength, SuggesterTest, PKIAuthenticationIntegrationTest,
DistanceUnitsTest, SpatialRPTFieldTypeTest, DistributedExpandComponentTest,
ShardSplitTest, SpellingQueryConverterTest, ParsingFieldUpdateProcessorsTest,
TestReversedWildcardFilterFactory, RemoteQueryErrorTest,
TestStressUserVersions, TestPKIAuthenticationPlugin, AnalysisErrorHandlingTest,
TestExpandComponent, TestReqParamsAPI, TestMiniSolrCloudClusterSSL,
DistanceFunctionTest, DistributedSuggestComponentTest, DataDrivenBlockJoinTest,
DistributedMLTComponentTest, TestShortCircuitedRequests,
TestFuzzyAnalyzedSuggestions, ShardRoutingCustomTest, BasicDistributedZkTest,
ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest,
OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest,
BasicZkTest, TestReplicationHandler, ClusterStateUpdateTest, ZkSolrClientTest,
TestZkChroot, ZkCLITest, AutoCommitTest, TestDistributedGrouping,
TestHashPartitioner, TestRangeQuery, TestCoreContainer, TestSolr4Spatial,
TestGroupingSearch, QueryElevationComponentTest, BasicFunctionalityTest,
DirectUpdateHandlerTest, TestIndexSearcher, TestCoreDiscovery]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCoreDiscovery
-Dtests.seed=45F43F257D825341 -Dtests.slow=true -Dtests.locale=en_SG
-Dtests.timezone=America/Guadeloupe -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestCoreDiscovery (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ERROR:
SolrIndexSearcher opens=27 closes=26
[junit4] > at
__randomizedtesting.SeedInfo.seed([45F43F257D825341]:0)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.endTrackingSearchers(SolrTestCaseJ4.java:453)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:225)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.core.TestCoreDiscovery:
[junit4] > 1) Thread[id=14668, name=searcherExecutor-6412-thread-1,
state=WAITING, group=TGRP-TestCoreDiscovery]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at
__randomizedtesting.SeedInfo.seed([45F43F257D825341]:0)Throwable #3:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie
threads that couldn't be terminated:
[junit4] > 1) Thread[id=14668, name=searcherExecutor-6412-thread-1,
state=WAITING, group=TGRP-TestCoreDiscovery]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at
java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
[junit4] > at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at
__randomizedtesting.SeedInfo.seed([45F43F257D825341]:0)
[junit4] Completed [404/566 (2!)] on J1 in 145.86s, 12 tests, 1 failure, 2
errors <<< FAILURES!
[...truncated 502 lines...]
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:784: The
following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:728: The
following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:59: The
following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build.xml:233:
The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/common-build.xml:516:
The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/lucene/common-build.xml:1436:
The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/lucene/common-build.xml:991:
There were test failures: 566 suites (8 ignored), 2253 tests, 3 suite-level
errors, 1 failure, 526 ignored (65 assumptions) [seed: 45F43F257D825341]
Total time: 60 minutes 2 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: [email protected]
For additional commands, e-mail: [email protected]
