Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21087/ Java: 32bit/jdk1.8.0_144 -server -XX:+UseConcMarkSweepGC
1 tests failed. FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication Error Message: Index: 0, Size: 0 Stack Trace: java.lang.IndexOutOfBoundsException: Index: 0, Size: 0 at __randomizedtesting.SeedInfo.seed([48E15F665733A397:5CA9043374341E89]:0) at java.util.ArrayList.rangeCheck(ArrayList.java:653) at java.util.ArrayList.get(ArrayList.java:429) at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication(TestReplicationHandler.java:561) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Build Log: [...truncated 12009 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> 329595 INFO (SUITE-TestReplicationHandler-seed#[48E15F665733A397]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/init-core-data-001 [junit4] 2> 329595 WARN (SUITE-TestReplicationHandler-seed#[48E15F665733A397]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2 [junit4] 2> 329595 INFO (SUITE-TestReplicationHandler-seed#[48E15F665733A397]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 329596 INFO (SUITE-TestReplicationHandler-seed#[48E15F665733A397]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) [junit4] 2> 329610 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestDetails [junit4] 2> 329610 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-001/collection1 [junit4] 2> 329615 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 329672 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@14b045a{/solr,null,AVAILABLE} [junit4] 2> 329673 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@181c177{HTTP/1.1,[http/1.1]}{127.0.0.1:46653} [junit4] 2> 329673 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.Server Started @331046ms [junit4] 2> 329673 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=46653} [junit4] 2> 329674 ERROR (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 329674 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 329674 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 329674 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 329674 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-15T06:59:10.063Z [junit4] 2> 329674 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-001/solr.xml [junit4] 2> 329677 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 329677 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 329679 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 329746 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 329759 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 329759 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 329761 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-001/. [junit4] 2> 329761 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 329761 INFO (coreLoadExecutor-1346-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 329801 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 329820 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 329822 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 329822 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-001/./collection1, trusted=true [junit4] 2> 329822 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 329822 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 329822 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-001/./collection1/data/] [junit4] 2> 329824 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6409164324619833] [junit4] 2> 329880 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 329880 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 329881 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1050784346, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 329882 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e12e1a[collection1] main] [junit4] 2> 329882 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-001/collection1/conf [junit4] 2> 329882 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 329882 INFO (coreLoadExecutor-1346-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 329883 INFO (searcherExecutor-1347-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e12e1a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 329884 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/collection1 [junit4] 2> 329886 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 329889 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5003a8{/solr,null,AVAILABLE} [junit4] 2> 329890 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1774b78{HTTP/1.1,[http/1.1]}{127.0.0.1:41051} [junit4] 2> 329890 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.Server Started @331262ms [junit4] 2> 329890 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=41051} [junit4] 2> 329890 ERROR (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 329890 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 329890 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 329890 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 329890 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-15T06:59:10.279Z [junit4] 2> 329891 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/solr.xml [junit4] 2> 329901 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 329901 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 329902 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 329962 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 329983 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 329983 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 329985 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/. [junit4] 2> 329985 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 329985 INFO (coreLoadExecutor-1356-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 330009 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 330047 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 330050 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 330050 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/./collection1, trusted=true [junit4] 2> 330050 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 330050 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 330050 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/./collection1/data/] [junit4] 2> 330052 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6409164324619833] [junit4] 2> 330086 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 330086 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 330087 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1050784346, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 330088 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e15884[collection1] main] [junit4] 2> 330088 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/collection1/conf [junit4] 2> 330090 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 330090 INFO (coreLoadExecutor-1356-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 330090 INFO (searcherExecutor-1357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e15884[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 330092 INFO (qtp1088460-10875) [ 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> 330092 INFO (indexFetcher-1361-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1 [junit4] 2> 330092 INFO (indexFetcher-1361-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0 [junit4] 2> 330092 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1774b78{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 330092 INFO (indexFetcher-1361-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 330092 INFO (indexFetcher-1361-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 330092 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=3640504 [junit4] 2> 330092 INFO (indexFetcher-1361-thread-1) [ x:collection1] o.a.s.h.IndexFetcher New index in Master. Deleting mine... [junit4] 2> 330093 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 330093 INFO (indexFetcher-1361-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 330093 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e63626: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@135a30d [junit4] 2> 330093 INFO (indexFetcher-1361-thread-1) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@85f3e3 commitCommandVersion:0 [junit4] 2> 330097 INFO (indexFetcher-1361-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@186ad77[collection1] main] [junit4] 2> 330097 INFO (indexFetcher-1361-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 330098 INFO (searcherExecutor-1357-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@186ad77[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 330101 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 330101 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1cdecc1: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@b09b48 [junit4] 2> 330105 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 330105 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7e778e: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5adbd9 [junit4] 2> 330105 INFO (coreCloseExecutor-1362-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@94af2a [junit4] 2> 330105 INFO (coreCloseExecutor-1362-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=9744170 [junit4] 2> 330105 INFO (coreCloseExecutor-1362-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1538598: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@9ae05c [junit4] 2> 330114 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5003a8{/solr,null,UNAVAILABLE} [junit4] 2> 330128 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 330131 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@165c05b{/solr,null,AVAILABLE} [junit4] 2> 330132 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@18ae24a{HTTP/1.1,[http/1.1]}{127.0.0.1:44991} [junit4] 2> 330132 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.Server Started @331505ms [junit4] 2> 330132 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=44991} [junit4] 2> 330132 ERROR (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 330132 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 330132 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 330132 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 330132 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-15T06:59:10.521Z [junit4] 2> 330133 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/solr.xml [junit4] 2> 330136 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 330136 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 330136 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 330180 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 330189 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 330190 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 330192 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/. [junit4] 2> 330192 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 330192 INFO (coreLoadExecutor-1369-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 330200 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 330217 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 330220 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 330220 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/./collection1, trusted=true [junit4] 2> 330220 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 330220 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 330220 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/./collection1/data/] [junit4] 2> 330222 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6409164324619833] [junit4] 2> 330265 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 330265 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 330265 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1050784346, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 330266 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@f27201[collection1] main] [junit4] 2> 330266 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-002/collection1/conf [junit4] 2> 330268 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 330268 INFO (coreLoadExecutor-1369-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 330269 INFO (searcherExecutor-1370-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f27201[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 330278 INFO (qtp1088460-10875) [ 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> 330279 INFO (qtp1088460-10876) [ 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=1 [junit4] 2> 330284 INFO (qtp1088460-10871) [ 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> 330284 INFO (qtp31374856-10914) [ 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=1 [junit4] 2> 330287 INFO (qtp1088460-10877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 1 [junit4] 2> 330287 INFO (qtp1088460-10876) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 330287 INFO (qtp1088460-10876) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1573e33 commitCommandVersion:0 [junit4] 2> 330291 INFO (qtp1088460-10876) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1310db6[collection1] main] [junit4] 2> 330291 INFO (qtp1088460-10876) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 330292 INFO (searcherExecutor-1347-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1310db6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))} [junit4] 2> 330292 INFO (qtp1088460-10876) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 5 [junit4] 2> 330303 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 330305 INFO (qtp1088460-10875) [ 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> 330305 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 330305 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1513321150676 [junit4] 2> 330305 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 330305 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 330305 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 330307 INFO (qtp1088460-10871) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=1 [junit4] 2> 330307 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 330308 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=367130177, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 330309 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 330309 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@4d1d61 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ee0983) [junit4] 2> 330310 INFO (qtp1088460-10877) [ 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> 330318 INFO (qtp1088460-10876) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330319 INFO (qtp1088460-10875) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330320 INFO (qtp1088460-10871) [ 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> 330321 INFO (qtp1088460-10877) [ 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> 330321 INFO (qtp1088460-10876) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330322 INFO (qtp1088460-10876) [ 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> 330323 INFO (qtp1088460-10871) [ 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> 330323 INFO (qtp1088460-10877) [ 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> 330334 INFO (qtp1088460-10875) [ 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> 330335 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1668) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@4d1d61 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@ee0983) [junit4] 2> 330336 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5810165303593985] [junit4] 2> 330336 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 330337 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@144d218[collection1] main] [junit4] 2> 330338 INFO (searcherExecutor-1370-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@144d218[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1)))} [junit4] 2> 330338 INFO (qtp31374856-10916) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:46653/solr/collection1/replication&command=fetchindex} status=0 QTime=35 [junit4] 2> 330347 INFO (qtp1088460-10876) [ 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> 330347 INFO (qtp31374856-10915) [ 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=1 [junit4] 2> 330348 INFO (qtp31374856-10914) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0 [junit4] 2> 330349 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs [junit4] 2> 330351 INFO (qtp1088460-10872) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 2 [junit4] 2> 330351 INFO (qtp1088460-10877) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 330352 INFO (qtp1088460-10877) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1573e33 commitCommandVersion:0 [junit4] 2> 330353 INFO (qtp1088460-10877) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1e1f024[collection1] main] [junit4] 2> 330354 INFO (qtp1088460-10877) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 330355 INFO (searcherExecutor-1347-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1e1f024[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1)))} [junit4] 2> 330355 INFO (qtp1088460-10877) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3 [junit4] 2> 330375 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 330376 INFO (qtp1088460-10871) [ 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> 330376 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 330376 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1513321150741 [junit4] 2> 330376 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 330376 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 1513321150676 [junit4] 2> 330376 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 330380 INFO (qtp1088460-10876) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=3 [junit4] 2> 330380 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 19 [junit4] 2> 330381 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=367130177, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 330382 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 330382 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@a42cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@15a701a) [junit4] 2> 330382 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 330382 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.doc because it already exists [junit4] 2> 330382 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tim because it already exists [junit4] 2> 330382 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists [junit4] 2> 330382 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists [junit4] 2> 330382 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tip because it already exists [junit4] 2> 330382 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists [junit4] 2> 330382 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists [junit4] 2> 330382 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists [junit4] 2> 330383 INFO (qtp1088460-10872) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330395 INFO (qtp1088460-10877) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330396 INFO (qtp1088460-10871) [ 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> 330397 INFO (qtp1088460-10876) [ 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> 330408 INFO (qtp1088460-10872) [ 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> 330409 INFO (qtp1088460-10877) [ 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> 330409 INFO (qtp1088460-10871) [ 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> 330410 INFO (qtp1088460-10876) [ 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> 330411 INFO (qtp1088460-10872) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330412 INFO (qtp1088460-10877) [ 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> 330412 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1748) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@a42cd6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@15a701a) [junit4] 2> 330423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5810165303593985] [junit4] 2> 330423 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 330424 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1fd9140[collection1] main] [junit4] 2> 330425 INFO (searcherExecutor-1370-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1fd9140[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1)))} [junit4] 2> 330425 INFO (qtp31374856-10909) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:46653/solr/collection1/replication&command=fetchindex} status=0 QTime=64 [junit4] 2> 330427 INFO (qtp1088460-10871) [ 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> 330428 INFO (qtp31374856-10910) [ 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> 330428 INFO (qtp31374856-10915) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=2 status=0 QTime=0 [junit4] 2> 330429 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 2 docs [junit4] 2> 330429 INFO (qtp1088460-10875) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 330430 INFO (qtp1088460-10875) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 330430 INFO (qtp1088460-10875) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1573e33 commitCommandVersion:0 [junit4] 2> 330432 INFO (qtp1088460-10875) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@411d00[collection1] main] [junit4] 2> 330432 INFO (qtp1088460-10875) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 330433 INFO (searcherExecutor-1347-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@411d00[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1) Uninverting(_2(8.0.0):C1)))} [junit4] 2> 330433 INFO (qtp1088460-10875) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3 [junit4] 2> 330451 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 330452 INFO (qtp1088460-10876) [ 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> 330452 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4 [junit4] 2> 330452 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1513321150819 [junit4] 2> 330452 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3 [junit4] 2> 330452 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 1513321150741 [junit4] 2> 330452 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 330453 INFO (qtp1088460-10871) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&tlogFiles=false&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 330457 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 28 [junit4] 2> 330460 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=367130177, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@d04fc5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1035384) [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.doc because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tim because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tip because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Lucene50_0.doc because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Lucene50_0.tim because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvd because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdx because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fnm because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.nvm because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.fdt because it already exists [junit4] 2> 330466 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1_Lucene50_0.tip because it already exists [junit4] 2> 330467 INFO (qtp1088460-10872) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330484 INFO (qtp1088460-10875) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330486 INFO (qtp1088460-10876) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdt&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330487 INFO (qtp1088460-10871) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fnm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330488 INFO (qtp1088460-10872) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.nvm&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330490 INFO (qtp1088460-10875) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330493 INFO (qtp1088460-10876) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_Lucene50_0.doc&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330495 INFO (qtp1088460-10871) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_Lucene50_0.tim&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330502 INFO (qtp1088460-10872) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2_Lucene50_0.tip&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330508 INFO (qtp1088460-10875) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 330508 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1828) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@d04fc5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1035384) [junit4] 2> 330509 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5810165303593985] [junit4] 2> 330509 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 330510 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e9142c[collection1] main] [junit4] 2> 330511 INFO (searcherExecutor-1370-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e9142c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C1) Uninverting(_1(8.0.0):C1) Uninverting(_2(8.0.0):C1)))} [junit4] 2> 330511 INFO (qtp31374856-10916) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:46653/solr/collection1/replication&command=fetchindex} status=0 QTime=76 [junit4] 2> 330512 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-003/collection1 [junit4] 2> 330514 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 330549 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13ac6d{/solr,null,AVAILABLE} [junit4] 2> 330550 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@c9415f{HTTP/1.1,[http/1.1]}{127.0.0.1:42661} [junit4] 2> 330550 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.Server Started @331922ms [junit4] 2> 330550 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=42661} [junit4] 2> 330550 ERROR (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 330550 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 330550 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 330550 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 330550 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-15T06:59:10.939Z [junit4] 2> 330551 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-003/solr.xml [junit4] 2> 330555 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 330555 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 330556 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 330597 INFO (qtp1088460-10876) [ 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> 330598 INFO (indexFetcher-1374-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4 [junit4] 2> 330598 INFO (indexFetcher-1374-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1513321150819 [junit4] 2> 330598 INFO (indexFetcher-1374-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 4 [junit4] 2> 330598 INFO (indexFetcher-1374-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's version: 1513321150819 [junit4] 2> 330598 INFO (indexFetcher-1374-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master. [junit4] 2> 330624 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 330638 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 330639 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 330642 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-003/. [junit4] 2> 330642 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 330643 INFO (coreLoadExecutor-1383-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 330667 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 330683 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 330685 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 330685 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-003/./collection1, trusted=true [junit4] 2> 330685 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10b114b [junit4] 2> 330685 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 330685 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-003/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-003/./collection1/data/] [junit4] 2> 330704 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6409164324619833] [junit4] 2> 330755 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 330755 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 330756 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1050784346, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 330756 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17c187[collection1] main] [junit4] 2> 330756 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-003/collection1/conf [junit4] 2> 330757 WARN (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 330757 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler No value set for 'pollInterval'. Timer Task not started. [junit4] 2> 330757 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml [junit4] 2> 330757 INFO (coreLoadExecutor-1383-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 330758 INFO (searcherExecutor-1384-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17c187[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 331271 WARN (qtp12793855-10941) [ x:collection1] o.a.s.h.ReplicationHandler Exception while invoking 'details' method for replication on master [junit4] 2> org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46653/solr: Expected mime type application/octet-stream but got text/html. <html> [junit4] 2> <head> [junit4] 2> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> [junit4] 2> <title>Error 404 </title> [junit4] 2> </head> [junit4] 2> <body> [junit4] 2> <h2>HTTP ERROR: 404</h2> [junit4] 2> <p>Problem accessing /solr/replication. Reason: [junit4] 2> <pre> Can not find: /solr/replication</pre></p> [junit4] 2> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.20.v20170531</a><hr/> [junit4] 2> </body> [junit4] 2> </html> [junit4] 2> [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:607) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1830) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:928) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:331) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:517) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> 331271 INFO (qtp12793855-10941) [ 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=4 [junit4] 2> 331283 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@c9415f{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 331283 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=27040171 [junit4] 2> 331285 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 331285 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@10315de: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@bf5cc1 [junit4] 2> 331292 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 331292 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1889a8e: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@b09b48 [junit4] 2> 331296 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 331296 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b6a632: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5adbd9 [junit4] 2> 331297 INFO (coreCloseExecutor-1388-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@413d2 [junit4] 2> 331297 INFO (coreCloseExecutor-1388-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=267218 [junit4] 2> 331297 INFO (coreCloseExecutor-1388-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@318070: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1f794a [junit4] 2> 331312 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@13ac6d{/solr,null,UNAVAILABLE} [junit4] 2> 331312 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestDetails [junit4] 2> 331313 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@181c177{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 331314 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=26611425 [junit4] 2> 331314 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 331315 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@16dd8fa: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@860e61 [junit4] 2> 331316 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 331316 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1087565: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@b09b48 [junit4] 2> 331317 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 331317 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c4dfec: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5adbd9 [junit4] 2> 331318 INFO (coreCloseExecutor-1390-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@24c8c4 [junit4] 2> 331318 INFO (coreCloseExecutor-1390-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=2410692 [junit4] 2> 331318 INFO (coreCloseExecutor-1390-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@effa39: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@c518ea [junit4] 2> 331339 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@14b045a{/solr,null,UNAVAILABLE} [junit4] 2> 331340 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@18ae24a{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 331340 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=25728094 [junit4] 2> 331340 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 331340 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5aacfd: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@18bdad0 [junit4] 2> 331342 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 331342 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@10b8cff: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@b09b48 [junit4] 2> 331356 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 331356 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1160580: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5adbd9 [junit4] 2> 331357 INFO (coreCloseExecutor-1392-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e473fa [junit4] 2> 331357 INFO (coreCloseExecutor-1392-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=14971898 [junit4] 2> 331357 INFO (coreCloseExecutor-1392-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1335ba3: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@173fc28 [junit4] 2> 331367 INFO (TEST-TestReplicationHandler.doTestDetails-seed#[48E15F665733A397]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@165c05b{/solr,null,UNAVAILABLE} [junit4] 2> 331369 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[48E15F665733A397]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestHandlerPathUnchanged [junit4] 2> 331370 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[48E15F665733A397]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001/solr-instance-004/collection1 [junit4] 2> 331372 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[48E15F665733A397]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 331379 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[48E15F665733A397]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@18d0aad{/solr,null,AVAILABLE} [junit4] 2> 331380 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged- [...truncated too long message...] doHandle(ServletHandler.java:582) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108) [junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [junit4] 2> at java.lang.Thread.run(Thread.java:748) [junit4] 2> [junit4] 2> 404765 INFO (qtp3092611-12023) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={tlogFile=../dir/traversal&command=filecontent} status=403 QTime=0 [junit4] 2> 404771 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestIllegalFilePaths [junit4] 2> 404776 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@fe4af0{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 404776 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=24039824 [junit4] 2> 404776 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 404776 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ac16db: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1c9b27 [junit4] 2> 404779 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 404779 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@8d2a4d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@b09b48 [junit4] 2> 404782 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 404782 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@9e1abd: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5adbd9 [junit4] 2> 404782 INFO (coreCloseExecutor-2037-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4e0ae5 [junit4] 2> 404782 INFO (coreCloseExecutor-2037-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=5114597 [junit4] 2> 404782 INFO (coreCloseExecutor-2037-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@a86f8a: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@17835c5 [junit4] 2> 404795 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b3acb0{/solr,null,UNAVAILABLE} [junit4] 2> 404795 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1c621dd{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 404795 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=4109716 [junit4] 2> 404795 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 404796 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@72b7a9: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@e53462 [junit4] 2> 404803 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 404803 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1931f2: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@b09b48 [junit4] 2> 404809 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 404809 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@138cd84: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5adbd9 [junit4] 2> 404810 INFO (coreCloseExecutor-2039-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@c4fbf9 [junit4] 2> 404811 INFO (coreCloseExecutor-2039-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=12909561 [junit4] 2> 404811 INFO (coreCloseExecutor-2039-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1779d00: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@12d1ad6 [junit4] 2> 404820 INFO (TEST-TestReplicationHandler.doTestIllegalFilePaths-seed#[48E15F665733A397]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@15f0999{/solr,null,UNAVAILABLE} [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_48E15F665733A397-001 [junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=11416, maxDocsPerChunk=135, blockSize=1), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=11416, blockSize=1)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@156cf45), locale=nl, timezone=Asia/Dushanbe [junit4] 2> NOTE: Linux 4.10.0-40-generic i386/Oracle Corporation 1.8.0_144 (32-bit)/cpus=8,threads=1,free=124470104,total=304050176 [junit4] 2> NOTE: All tests run in this JVM: [SolrMetricReporterTest, TestSimpleTextCodec, CachingDirectoryFactoryTest, TestIntervalFaceting, TestTrackingShardHandlerFactory, LeaderElectionIntegrationTest, TermsComponentTest, CdcrVersionReplicationTest, SolrCloudExampleTest, BasicFunctionalityTest, TestPhraseSuggestions, BooleanFieldTest, DateMathParserTest, TestMergePolicyConfig, TestObjectReleaseTracker, DateRangeFieldTest, RegexBytesRefFilterTest, PropertiesRequestHandlerTest, TestPostingsSolrHighlighter, StatsReloadRaceTest, TestDistribStateManager, QueryParsingTest, TestStressLiveNodes, TestSolrCloudSnapshots, TestInPlaceUpdatesDistrib, DataDrivenBlockJoinTest, CdcrReplicationDistributedZkTest, TestReplicationHandler] [junit4] Completed [134/764 (1!)] on J0 in 75.25s, 16 tests, 1 error, 1 skipped <<< FAILURES! [...truncated 45130 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org