Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20723/ Java: 64bit/jdk-9 -XX:-UseCompressedOops -XX:+UseG1GC --illegal-access=deny
1 tests failed. FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication Error Message: Index 0 out-of-bounds for length 0 Stack Trace: java.lang.IndexOutOfBoundsException: Index 0 out-of-bounds for length 0 at __randomizedtesting.SeedInfo.seed([58986B84C8F2760:11C1DDED6F889A7E]:0) at java.base/jdk.internal.util.Preconditions.outOfBounds(Preconditions.java:64) at java.base/jdk.internal.util.Preconditions.outOfBoundsCheckIndex(Preconditions.java:70) at java.base/jdk.internal.util.Preconditions.checkIndex(Preconditions.java:248) at java.base/java.util.Objects.checkIndex(Objects.java:372) at java.base/java.util.ArrayList.get(ArrayList.java:439) at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigReplication(TestReplicationHandler.java:561) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) 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 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.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 12268 lines...] [junit4] Suite: org.apache.solr.handler.TestReplicationHandler [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_58986B84C8F2760-001/init-core-data-001 [junit4] 2> 685033 INFO (SUITE-TestReplicationHandler-seed#[58986B84C8F2760]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 685034 INFO (SUITE-TestReplicationHandler-seed#[58986B84C8F2760]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None") [junit4] 2> 685034 INFO (SUITE-TestReplicationHandler-seed#[58986B84C8F2760]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 685035 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestRepeater [junit4] 2> 685035 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] 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_58986B84C8F2760-001/solr-instance-001/collection1 [junit4] 2> 685036 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 685037 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@710c84f1{/solr,null,AVAILABLE} [junit4] 2> 685038 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4956e161{HTTP/1.1,[http/1.1]}{127.0.0.1:35111} [junit4] 2> 685038 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.Server Started @687212ms [junit4] 2> 685038 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_58986B84C8F2760-001/solr-instance-001/collection1/data, hostPort=35111} [junit4] 2> 685038 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 685038 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 685039 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 685039 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 685039 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-24T10:03:15.384071Z [junit4] 2> 685039 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] 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_58986B84C8F2760-001/solr-instance-001/solr.xml [junit4] 2> 685040 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 685040 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 685041 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 685058 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685064 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685064 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] 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_58986B84C8F2760-001/solr-instance-001/. [junit4] 2> 685065 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 685066 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 685070 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 685073 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 685074 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 685074 INFO (coreLoadExecutor-2075-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-001/./collection1, trusted=true [junit4] 2> 685074 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685074 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 685074 INFO (coreLoadExecutor-2075-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_58986B84C8F2760-001/solr-instance-001/./collection1/data/] [junit4] 2> 685075 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=96.91796875, floorSegmentMB=0.6064453125, forceMergeDeletesPctAllowed=4.308218772459863, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 685100 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 685100 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 685100 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.18851375900268819] [junit4] 2> 685100 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@49336b6e[collection1] main] [junit4] 2> 685100 INFO (coreLoadExecutor-2075-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-001/collection1/conf [junit4] 2> 685101 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl [junit4] 2> 685101 INFO (coreLoadExecutor-2075-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 685101 INFO (searcherExecutor-2076-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@49336b6e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 685102 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] 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_58986B84C8F2760-001/solr-instance-002/collection1 [junit4] 2> 685103 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 685104 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b88694d{/solr,null,AVAILABLE} [junit4] 2> 685104 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@74e7b5ef{HTTP/1.1,[http/1.1]}{127.0.0.1:34231} [junit4] 2> 685104 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.Server Started @687278ms [junit4] 2> 685104 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_58986B84C8F2760-001/solr-instance-002/collection1/data, hostPort=34231} [junit4] 2> 685104 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 685104 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 685104 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 685104 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 685104 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-24T10:03:15.449761Z [junit4] 2> 685104 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] 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_58986B84C8F2760-001/solr-instance-002/solr.xml [junit4] 2> 685106 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 685106 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 685107 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 685125 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685132 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685132 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685133 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] 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_58986B84C8F2760-001/solr-instance-002/. [junit4] 2> 685133 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 685133 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 685137 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 685140 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 685141 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 685141 INFO (coreLoadExecutor-2085-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-002/./collection1, trusted=true [junit4] 2> 685141 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685141 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 685141 INFO (coreLoadExecutor-2085-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_58986B84C8F2760-001/solr-instance-002/./collection1/data/] [junit4] 2> 685142 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=96.91796875, floorSegmentMB=0.6064453125, forceMergeDeletesPctAllowed=4.308218772459863, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 685164 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 685164 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 685164 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.18851375900268819] [junit4] 2> 685164 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3cb88b4b[collection1] main] [junit4] 2> 685164 INFO (coreLoadExecutor-2085-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-002/collection1/conf [junit4] 2> 685165 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms [junit4] 2> 685165 INFO (coreLoadExecutor-2085-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 685166 INFO (searcherExecutor-2086-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3cb88b4b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 685173 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@74e7b5ef{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 685173 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1741338489 [junit4] 2> 685173 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 685173 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60967f45: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@7797ad8 [junit4] 2> 685178 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 685178 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3288c9ee: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@302e7a50 [junit4] 2> 685181 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 685181 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6afcb1ac: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3411e88d [junit4] 2> 685181 INFO (coreCloseExecutor-2091-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@669bbe42 [junit4] 2> 685181 INFO (coreCloseExecutor-2091-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1721482818 [junit4] 2> 685181 INFO (coreCloseExecutor-2091-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@45a0ddd3: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@320e1fb5 [junit4] 2> 685186 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b88694d{/solr,null,UNAVAILABLE} [junit4] 2> 685187 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 685188 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@76974b18{/solr,null,AVAILABLE} [junit4] 2> 685188 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3e224b6d{HTTP/1.1,[http/1.1]}{127.0.0.1:43779} [junit4] 2> 685188 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.Server Started @687362ms [junit4] 2> 685188 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_58986B84C8F2760-001/solr-instance-002/collection1/data, hostPort=43779} [junit4] 2> 685189 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 685189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 685189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 685189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 685189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-24T10:03:15.534197Z [junit4] 2> 685189 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] 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_58986B84C8F2760-001/solr-instance-002/solr.xml [junit4] 2> 685190 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 685190 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 685191 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 685208 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685214 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685214 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685215 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] 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_58986B84C8F2760-001/solr-instance-002/. [junit4] 2> 685215 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 685215 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 685220 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 685225 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 685226 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 685226 INFO (coreLoadExecutor-2098-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-002/./collection1, trusted=true [junit4] 2> 685226 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685226 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 685226 INFO (coreLoadExecutor-2098-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-002/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_58986B84C8F2760-001/solr-instance-002/./collection1/data/] [junit4] 2> 685228 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=96.91796875, floorSegmentMB=0.6064453125, forceMergeDeletesPctAllowed=4.308218772459863, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 685254 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 685254 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 685255 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.18851375900268819] [junit4] 2> 685255 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@43a796d9[collection1] main] [junit4] 2> 685255 INFO (coreLoadExecutor-2098-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-002/collection1/conf [junit4] 2> 685256 INFO (coreLoadExecutor-2098-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 685256 INFO (searcherExecutor-2099-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@43a796d9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 685257 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] 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_58986B84C8F2760-001/solr-instance-003/collection1 [junit4] 2> 685258 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 685262 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@585da24d{/solr,null,AVAILABLE} [junit4] 2> 685262 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@75454b18{HTTP/1.1,[http/1.1]}{127.0.0.1:39869} [junit4] 2> 685262 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.e.j.s.Server Started @687436ms [junit4] 2> 685263 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_58986B84C8F2760-001/solr-instance-003/collection1/data, hostPort=39869} [junit4] 2> 685263 ERROR (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 685263 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0 [junit4] 2> 685263 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 685263 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null [junit4] 2> 685263 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-10-24T10:03:15.608432Z [junit4] 2> 685263 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] 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_58986B84C8F2760-001/solr-instance-003/solr.xml [junit4] 2> 685265 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 685265 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 685266 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 685288 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685294 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685294 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685295 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] 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_58986B84C8F2760-001/solr-instance-003/. [junit4] 2> 685295 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 685295 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 685301 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 685306 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 685307 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id [junit4] 2> 685307 INFO (coreLoadExecutor-2108-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-003/./collection1, trusted=true [junit4] 2> 685307 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@70f370e2 [junit4] 2> 685307 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 685307 INFO (coreLoadExecutor-2108-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-003/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_58986B84C8F2760-001/solr-instance-003/./collection1/data/] [junit4] 2> 685309 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=96.91796875, floorSegmentMB=0.6064453125, forceMergeDeletesPctAllowed=4.308218772459863, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 685330 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 685330 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 685330 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.18851375900268819] [junit4] 2> 685331 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@78f8bab7[collection1] main] [junit4] 2> 685331 INFO (coreLoadExecutor-2108-thread-1) [ ] 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_58986B84C8F2760-001/solr-instance-003/collection1/conf [junit4] 2> 685331 WARN (coreLoadExecutor-2108-thread-1) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 685332 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.h.ReplicationHandler No value set for 'pollInterval'. Timer Task not started. [junit4] 2> 685332 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml [junit4] 2> 685332 INFO (coreLoadExecutor-2108-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 685332 INFO (searcherExecutor-2109-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@78f8bab7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 685334 INFO (qtp1722155760-4472) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0 [junit4] 2> 685335 INFO (qtp1722155760-4473) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0 [junit4] 2> 685335 INFO (qtp1722155760-4474) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 685335 INFO (qtp1722155760-4467) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 685335 INFO (qtp1722155760-4467) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@37b567af commitCommandVersion:0 [junit4] 2> 685336 INFO (qtp1722155760-4467) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@37df1129[collection1] main] [junit4] 2> 685337 INFO (qtp1722155760-4467) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 685337 INFO (searcherExecutor-2076-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@37df1129[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C3)))} [junit4] 2> 685337 INFO (qtp1722155760-4467) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2 [junit4] 2> 685346 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 685347 INFO (qtp1722155760-4473) [ ] 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> 685347 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 685347 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1508839395680 [junit4] 2> 685347 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 685347 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 685347 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 685348 INFO (qtp1722155760-4474) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 685349 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 685349 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=20.654296875, floorSegmentMB=1.037109375, forceMergeDeletesPctAllowed=14.847801835018396, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 685349 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 685349 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@6ca6d170 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f5767) [junit4] 2> 685350 INFO (qtp1722155760-4467) [ ] 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> 685354 INFO (qtp1722155760-4468) [ ] 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> 685355 INFO (qtp1722155760-4473) [ ] 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> 685356 INFO (qtp1722155760-4474) [ ] 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> 685357 INFO (qtp1722155760-4467) [ ] 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> 685357 INFO (qtp1722155760-4468) [ ] 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> 685358 INFO (qtp1722155760-4473) [ ] 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> 685358 INFO (qtp1722155760-4474) [ ] 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> 685359 INFO (qtp1722155760-4467) [ ] 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> 685359 INFO (qtp1722155760-4468) [ ] 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> 685359 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1745) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@6ca6d170 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@794f5767) [junit4] 2> 685360 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=31.5673828125, floorSegmentMB=1.828125, forceMergeDeletesPctAllowed=18.5680991437474, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 685361 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 685361 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@65e3fdad[collection1] main] [junit4] 2> 685362 INFO (searcherExecutor-2109-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@65e3fdad[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C3)))} [junit4] 2> 685363 INFO (qtp1947757454-4527) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:35111/solr/collection1/replication&command=fetchindex} status=0 QTime=17 [junit4] 2> 685371 INFO (qtp1947757454-4529) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0 [junit4] 2> 685371 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 685373 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 685374 INFO (qtp1947757454-4524) [ ] 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> 685374 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 685374 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1508839395680 [junit4] 2> 685374 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 1 [junit4] 2> 685374 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 0 [junit4] 2> 685374 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 685375 INFO (qtp1947757454-4524) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 685376 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 685377 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=20.654296875, floorSegmentMB=1.037109375, forceMergeDeletesPctAllowed=14.847801835018396, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 685377 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 685377 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@37e668d0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d0c1769) [junit4] 2> 685377 INFO (qtp1947757454-4523) [ ] 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> 685379 INFO (qtp1947757454-4529) [ ] 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> 685396 INFO (qtp1947757454-4527) [ ] 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> 685397 INFO (qtp1947757454-4527) [ ] 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> 685398 INFO (qtp1947757454-4523) [ ] 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> 685399 INFO (qtp1947757454-4529) [ ] 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> 685400 INFO (qtp1947757454-4524) [ ] 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> 685400 INFO (qtp1947757454-4527) [ ] 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> 685401 INFO (qtp1947757454-4523) [ ] 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> 685403 INFO (qtp1947757454-4529) [ ] 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> 685404 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1745) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@37e668d0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d0c1769) [junit4] 2> 685405 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=31.5673828125, floorSegmentMB=1.828125, forceMergeDeletesPctAllowed=18.5680991437474, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 685406 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 685407 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@61abba75[collection1] main] [junit4] 2> 685409 INFO (searcherExecutor-2099-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@61abba75[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C3)))} [junit4] 2> 685409 INFO (qtp1421707120-4510) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:39869/solr/collection1/replication&command=fetchindex} status=0 QTime=36 [junit4] 2> 685411 INFO (qtp1421707120-4512) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0 [junit4] 2> 685411 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 685412 INFO (qtp1722155760-4472) [ ] 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> 685412 INFO (qtp1722155760-4474) [ ] 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> 685414 WARN (qtp1947757454-4522) [ ] 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:TEST_PORT/solr: Expected mime type application/octet-stream but got text/html. <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN"> [junit4] 2> <html><head> [junit4] 2> <title>404 Not Found</title> [junit4] 2> </head><body> [junit4] 2> <h1>Not Found</h1> [junit4] 2> <p>The requested URL /solr/replication was not found on this server.</p> [junit4] 2> <hr> [junit4] 2> <address>Apache/2.4.18 (Ubuntu) Server at 127.0.0.1 Port 80</address> [junit4] 2> </body></html> [junit4] 2> [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1823) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:954) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:332) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2484) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516) [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.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 685415 INFO (qtp1947757454-4522) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2 [junit4] 2> 685416 INFO (qtp1722155760-4467) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 685416 INFO (qtp1947757454-4527) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 685417 WARN (qtp1947757454-4523) [ ] 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:TEST_PORT/solr: Expected mime type application/octet-stream but got text/html. <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN"> [junit4] 2> <html><head> [junit4] 2> <title>404 Not Found</title> [junit4] 2> </head><body> [junit4] 2> <h1>Not Found</h1> [junit4] 2> <p>The requested URL /solr/replication was not found on this server.</p> [junit4] 2> <hr> [junit4] 2> <address>Apache/2.4.18 (Ubuntu) Server at 127.0.0.1 Port 80</address> [junit4] 2> </body></html> [junit4] 2> [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1823) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:954) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:332) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2484) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516) [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.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 685418 INFO (qtp1947757454-4523) [ ] 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> 685418 WARN (qtp1947757454-4529) [ ] 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:TEST_PORT/solr: Expected mime type application/octet-stream but got text/html. <!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN"> [junit4] 2> <html><head> [junit4] 2> <title>404 Not Found</title> [junit4] 2> </head><body> [junit4] 2> <h1>Not Found</h1> [junit4] 2> <p>The requested URL /solr/replication was not found on this server.</p> [junit4] 2> <hr> [junit4] 2> <address>Apache/2.4.18 (Ubuntu) Server at 127.0.0.1 Port 80</address> [junit4] 2> </body></html> [junit4] 2> [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getDetails(IndexFetcher.java:1823) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.getReplicationDetails(ReplicationHandler.java:954) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.handleRequestBody(ReplicationHandler.java:332) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2484) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516) [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.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 685419 INFO (qtp1947757454-4529) [ ] 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> 685420 INFO (qtp1947757454-4524) [ ] 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> 685421 INFO (qtp1421707120-4505) [ ] 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> 685421 INFO (qtp1947757454-4522) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 685422 INFO (qtp1421707120-4510) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 685423 INFO (qtp1947757454-4523) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 0 [junit4] 2> 685423 INFO (qtp1947757454-4529) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0 [junit4] 2> 685424 INFO (qtp1947757454-4524) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0 [junit4] 2> 685424 INFO (qtp1947757454-4524) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0 [junit4] 2> 685424 INFO (qtp1947757454-4523) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 685424 INFO (qtp1947757454-4523) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4d5e2468 commitCommandVersion:0 [junit4] 2> 685426 INFO (qtp1947757454-4523) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@24a7bc23[collection1] main] [junit4] 2> 685426 INFO (qtp1947757454-4523) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 685427 INFO (searcherExecutor-2109-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@24a7bc23[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C4)))} [junit4] 2> 685427 INFO (qtp1947757454-4523) [ ] 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> 685428 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 685430 INFO (qtp1722155760-4473) [ ] 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> 685430 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 685430 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1508839395680 [junit4] 2> 685430 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 3 [junit4] 2> 685430 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1508839395769 [junit4] 2> 685430 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 685431 INFO (qtp1722155760-4472) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 685431 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 10 [junit4] 2> 685431 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=true) to MockDirectoryWrapper(RAMDirectory@26e12a86 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@445bfe01) [junit4] 2> 685432 INFO (qtp1722155760-4474) [ ] 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> 685433 INFO (qtp1722155760-4467) [ ] 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> 685434 INFO (qtp1722155760-4473) [ ] 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> 685434 INFO (qtp1722155760-4472) [ ] 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> 685435 INFO (qtp1722155760-4474) [ ] 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> 685436 INFO (qtp1722155760-4467) [ ] 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> 685436 INFO (qtp1722155760-4473) [ ] 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> 685437 INFO (qtp1722155760-4472) [ ] 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> 685437 INFO (qtp1722155760-4474) [ ] 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> 685438 INFO (qtp1722155760-4467) [ ] 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> 685438 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=true,bytesDownloaded=1745) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@26e12a86 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@445bfe01) [junit4] 2> 685438 INFO (explicit-fetchindex-cmd) [ ] o.a.s.c.SolrCore Updating index properties... index=index.20171024050315776 [junit4] 2> 685438 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher removing old index directory MockDirectoryWrapper(RAMDirectory@646ba564 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36aa49f3) [junit4] 2> 685439 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=20.654296875, floorSegmentMB=1.037109375, forceMergeDeletesPctAllowed=14.847801835018396, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 685439 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 685439 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5a0ffcc1[collection1] main] [junit4] 2> 685440 INFO (searcherExecutor-2109-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5a0ffcc1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C3)))} [junit4] 2> 685440 INFO (qtp1947757454-4528) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:35111/solr/collection1/replication&command=fetchindex} status=0 QTime=12 [junit4] 2> 685441 INFO (qtp1947757454-4529) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0 [junit4] 2> 685441 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 685442 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 685443 INFO (qtp1947757454-4527) [ ] 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> 685443 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 2 [junit4] 2> 685443 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1508839395680 [junit4] 2> 685443 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 685443 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1508839395680 [junit4] 2> 685443 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave in sync with master. [junit4] 2> 685443 INFO (qtp1421707120-4511) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:39869/solr/collection1/replication&command=fetchindex} status=0 QTime=1 [junit4] 2> 685444 INFO (qtp1421707120-4506) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=3 status=0 QTime=0 [junit4] 2> 685444 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[58986B84C8F2760]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 3 docs [junit4] 2> 685445 INFO (qtp1722155760-4468) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0 [junit4] 2> 685445 INFO (qtp1722155760-4472) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0 [junit4] 2> 685446 INFO (qtp1722155760-4474) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0 [junit4] 2> 685446 INFO (qtp1722155760-4467) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 685446 INFO (qtp1722155760-4467) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@37b567af commitCommandVersion:0 [junit4] 2> 685447 INFO (qtp1722155760-4467) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@250a5a55[collection1] main] [junit4] 2> 685448 INFO (qtp1722155760-4467) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 685448 INFO (searcherExecutor-2076-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@250a5a55[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C3) Uninverting(_1(8.0.0):C3)))} [junit4] 2> 685448 INFO (qtp1722155760-4467) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2 [junit4] 2> 685449 WARN (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix [junit4] 2> 685450 INFO (qtp1722155760-4473) [ ] 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> 685450 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's generation: 3 [junit4] 2> 685450 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Master's version: 1508839395791 [junit4] 2> 685450 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's generation: 2 [junit4] 2> 685450 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Slave's version: 1508839395680 [junit4] 2> 685450 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting replication process [junit4] 2> 685451 INFO (qtp1722155760-4472) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0 [junit4] 2> 685451 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Number of files in latest index in master: 19 [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=33, maxMergeAtOnceExplicit=19, maxMergedSegmentMB=20.654296875, floorSegmentMB=1.037109375, forceMergeDeletesPctAllowed=14.847801835018396, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(RAMDirectory@772e1b3e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f6428f6) [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.doc because it already exists [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tim because it already exists [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvd because it already exists [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdx because it already exists [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0_Lucene50_0.tip because it already exists [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fdt because it already exists [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.fnm because it already exists [junit4] 2> 685452 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Skipping download for _0.nvm because it already exists [junit4] 2> 685453 INFO (qtp1722155760-4474) [ ] 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> 685454 INFO (qtp1722155760-4467) [ ] 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> 685454 INFO (qtp1722155760-4473) [ ] 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> 685455 INFO (qtp1722155760-4472) [ ] 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> 685456 INFO (qtp1722155760-4474) [ ] 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> 685456 INFO (qtp1722155760-4467) [ ] 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> 685457 INFO (qtp1722155760-4473) [ ] 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> 685457 INFO (qtp1722155760-4472) [ ] 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> 685458 INFO (qtp1722155760-4474) [ ] 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> 685459 INFO (qtp1722155760-4467) [ ] 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> 685459 INFO (explicit-fetchindex-cmd) [ ] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1838) : 0 secs (null bytes/sec) to MockDirectoryWrapper(RAMDirectory@772e1b3e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f6428f6) [junit4] 2> 685460 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=31.5673828125, floorSegmentMB=1.828125, forceMergeDeletesPctAllowed=18.5680991437474, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 685460 INFO (explicit-fetchindex-cmd) [ ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. [junit4] 2> 685461 INFO (explicit-fetchindex-cmd) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@57d49f98[collection1] [...truncated too long message...] empty4)&sort=id+desc&wt=javabin&version=2} hits=4 status=0 QTime=0 [junit4] 2> 762459 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs [junit4] 2> 762459 INFO (qtp158990933-5600) [ ] 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> 762460 INFO (qtp158990933-5595) [ ] 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> 762460 INFO (qtp158990933-5594) [ ] 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> 762460 INFO (qtp1944243330-5611) [ ] 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> 762461 INFO (qtp158990933-5599) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 762461 INFO (qtp1944243330-5618) [ ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0 [junit4] 2> 762461 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEmptyCommits [junit4] 2> 762465 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7b5f30af{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 762465 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=908948883 [junit4] 2> 762465 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 762466 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@b7f2b99: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5c49457e [junit4] 2> 762470 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 762470 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5db49c8d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@302e7a50 [junit4] 2> 762474 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 762474 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@56754e80: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3411e88d [junit4] 2> 762475 INFO (coreCloseExecutor-2756-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@63362e5f [junit4] 2> 762475 INFO (coreCloseExecutor-2756-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1664495199 [junit4] 2> 762475 INFO (coreCloseExecutor-2756-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@294cb503: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@f55ffe8 [junit4] 2> 762485 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3ee9b623{/solr,null,UNAVAILABLE} [junit4] 2> 762487 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7c350d39{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 762487 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1764152043 [junit4] 2> 762488 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 762488 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@39be0aa9: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@5d501493 [junit4] 2> 762493 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 762493 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@11bd7cb: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@302e7a50 [junit4] 2> 762498 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 762498 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@70ecb4b4: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@3411e88d [junit4] 2> 762498 INFO (coreCloseExecutor-2758-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@60329d8e [junit4] 2> 762498 INFO (coreCloseExecutor-2758-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1613929870 [junit4] 2> 762498 INFO (coreCloseExecutor-2758-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@cf7a438: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6e4f09fd [junit4] 2> 762512 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[58986B84C8F2760]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6a31c285{/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_58986B84C8F2760-001 [junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=5075, maxDocsPerChunk=484, blockSize=1), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=5075, blockSize=1)), sim=RandomSimilarity(queryNorm=false): {}, locale=en-AT, timezone=America/Resolute [junit4] 2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation 9 (64-bit)/cpus=8,threads=1,free=254629016,total=536870912 [junit4] 2> NOTE: All tests run in this JVM: [TestShardHandlerFactory, TestFieldCache, MetricsConfigTest, DistanceUnitsTest, TestSQLHandlerNonCloud, TlogReplayBufferedWhileIndexingTest, PrimitiveFieldTypeTest, SpellCheckComponentTest, HdfsBasicDistributedZkTest, TestConfigOverlay, DateMathParserTest, TestExpandComponent, TestDistribIDF, ChaosMonkeyNothingIsSafeWithPullReplicasTest, RestartWhileUpdatingTest, PeerSyncTest, TestPayloadCheckQParserPlugin, JsonLoaderTest, TestRequestStatusCollectionAPI, TestUnifiedSolrHighlighter, TestStressVersions, CdcrReplicationHandlerTest, MissingSegmentRecoveryTest, TestGraphTermsQParserPlugin, TestManagedResourceStorage, DocumentBuilderTest, TestSubQueryTransformerCrossCore, SubstringBytesRefFilterTest, SolrGraphiteReporterTest, TestUpdate, UpdateRequestProcessorFactoryTest, HdfsWriteToMultipleCollectionsTest, BJQParserTest, TestWordDelimiterFilterFactory, TestPHPSerializedResponseWriter, BaseCdcrDistributedZkTest, SolrMetricReporterTest, TestBinaryField, TestConfigsApi, NoCacheHeaderTest, CursorPagingTest, SolrCoreTest, ForceLeaderTest, SpellCheckCollatorTest, TestRecovery, DistributedMLTComponentTest, TestConfigReload, TestLeaderElectionWithEmptyReplica, DistributedSuggestComponentTest, TestStressCloudBlindAtomicUpdates, TestCrossCoreJoin, AnalysisAfterCoreReloadTest, TestReversedWildcardFilterFactory, LeaderElectionContextKeyTest, SliceStateTest, TestCopyFieldCollectionResource, SolrIndexMetricsTest, UtilsToolTest, FastVectorHighlighterTest, TestConfigSetsAPI, SpatialFilterTest, TestMergePolicyConfig, TestRuleBasedAuthorizationPlugin, TestReplicationHandler] [junit4] Completed [252/745 (1!)] on J0 in 77.49s, 16 tests, 1 error, 1 skipped <<< FAILURES! [...truncated 41760 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org