You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2016/10/11 06:40:08 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 3598
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3598/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory] org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43) at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347) at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386) at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268) at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397) at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory]
org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386)
at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([976C4812E95EBCC]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:261)
at sun.reflect.GeneratedMethodAccessor17.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10810 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/init-core-data-001
[junit4] 2> 357600 INFO (SUITE-TestReplicationHandler-seed#[976C4812E95EBCC]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 357601 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEmptyCommits
[junit4] 2> 357602 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-001/collection1
[junit4] 2> 357610 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 357612 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@45b4fdd7{/solr,null,AVAILABLE}
[junit4] 2> 357621 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5879c9b6{HTTP/1.1,[http/1.1]}{127.0.0.1:54189}
[junit4] 2> 357621 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server Started @362863ms
[junit4] 2> 357621 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=54189}
[junit4] 2> 357622 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 357622 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 357622 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 357622 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-11T05:15:43.745Z
[junit4] 2> 357623 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-001/solr.xml
[junit4] 2> 357651 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-001/.
[junit4] 2> 357651 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 357665 WARN (coreLoadExecutor-1011-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 357666 INFO (coreLoadExecutor-1011-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 357672 INFO (coreLoadExecutor-1011-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 357677 INFO (coreLoadExecutor-1011-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 357680 INFO (coreLoadExecutor-1011-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-001/./collection1
[junit4] 2> 357681 INFO (coreLoadExecutor-1011-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-001/./collection1/data/]
[junit4] 2> 357681 INFO (coreLoadExecutor-1011-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.11239653555112072]
[junit4] 2> 357691 INFO (coreLoadExecutor-1011-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 357691 INFO (coreLoadExecutor-1011-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 357692 INFO (coreLoadExecutor-1011-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=95.794921875, floorSegmentMB=0.4208984375, forceMergeDeletesPctAllowed=8.115832711559346, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 357692 INFO (coreLoadExecutor-1011-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@71b96c9d[collection1] main]
[junit4] 2> 357692 INFO (coreLoadExecutor-1011-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-001/collection1/conf
[junit4] 2> 357693 INFO (coreLoadExecutor-1011-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 357693 INFO (coreLoadExecutor-1011-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 357693 INFO (searcherExecutor-1012-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@71b96c9d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 358166 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-002/collection1
[junit4] 2> 358170 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 358172 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@da9fd28{/solr,null,AVAILABLE}
[junit4] 2> 358173 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@752d2a29{HTTP/1.1,[http/1.1]}{127.0.0.1:54190}
[junit4] 2> 358173 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server Started @363415ms
[junit4] 2> 358173 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=54190}
[junit4] 2> 358174 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 358174 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 358174 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 358174 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-11T05:15:44.297Z
[junit4] 2> 358175 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-002/solr.xml
[junit4] 2> 358183 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-002/.
[junit4] 2> 358183 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 358190 WARN (coreLoadExecutor-1021-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 358190 INFO (coreLoadExecutor-1021-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 358196 INFO (coreLoadExecutor-1021-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 358199 INFO (coreLoadExecutor-1021-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 358200 INFO (coreLoadExecutor-1021-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-002/./collection1
[junit4] 2> 358200 INFO (coreLoadExecutor-1021-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-002/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-002/./collection1/data/]
[junit4] 2> 358201 INFO (coreLoadExecutor-1021-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.11239653555112072]
[junit4] 2> 358207 INFO (coreLoadExecutor-1021-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 358208 INFO (coreLoadExecutor-1021-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 358208 INFO (coreLoadExecutor-1021-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=95.794921875, floorSegmentMB=0.4208984375, forceMergeDeletesPctAllowed=8.115832711559346, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 358208 INFO (coreLoadExecutor-1021-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7e0622f0[collection1] main]
[junit4] 2> 358208 INFO (coreLoadExecutor-1021-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-002/collection1/conf
[junit4] 2> 358211 INFO (coreLoadExecutor-1021-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 358211 INFO (searcherExecutor-1022-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7e0622f0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 358697 INFO (qtp1758624125-2266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 358699 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 1
[junit4] 2> 358700 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 358700 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1631b147
[junit4] 2> 358708 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@30b4d67d[collection1] main]
[junit4] 2> 358708 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 358708 INFO (searcherExecutor-1012-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@30b4d67d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
[junit4] 2> 358708 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 8
[junit4] 2> 358721 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 358724 INFO (qtp1758624125-2270) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 358724 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 358724 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1476162944823
[junit4] 2> 358724 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 358724 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 358730 INFO (qtp1758624125-2271) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=5
[junit4] 2> 358730 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 4
[junit4] 2> 358732 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=612816853, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 358732 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 358733 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@32758c62 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62359fd9; maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 358734 INFO (qtp1758624125-2264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358735 INFO (qtp1758624125-2266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358737 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=_0.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358739 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=2&qt=/replication&file=segments_2&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358739 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1909) : 0 secs (null bytes/sec) to MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@32758c62 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62359fd9; maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 358740 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=97.3955078125, floorSegmentMB=1.9404296875, forceMergeDeletesPctAllowed=19.883416600001897, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7863546212186346
[junit4] 2> 358741 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 358742 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@47457d87[collection1] main]
[junit4] 2> 358742 INFO (searcherExecutor-1022-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@47457d87[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1)))}
[junit4] 2> 358742 INFO (qtp546545157-2283) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:54189/solr/collection1/replication&command=fetchindex} status=0 QTime=22
[junit4] 2> 358751 INFO (qtp546545157-2285) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty1&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 358752 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 358753 INFO (qtp1758624125-2269) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 358754 INFO (qtp1758624125-2270) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 358756 INFO (qtp1758624125-2271) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 358756 INFO (qtp546545157-2286) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1
[junit4] 2> 358757 INFO (qtp1758624125-2264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 358758 INFO (qtp546545157-2287) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 358759 INFO (qtp1758624125-2264) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 358759 INFO (qtp1758624125-2264) [ x:collection1] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 358760 INFO (qtp1758624125-2264) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 358760 INFO (qtp1758624125-2264) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 1
[junit4] 2> 358761 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 358762 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 358763 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 358763 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1476162944823
[junit4] 2> 358763 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 358763 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 358763 INFO (qtp546545157-2287) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:54189/solr/collection1/replication&command=fetchindex} status=0 QTime=2
[junit4] 2> 358764 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 358765 INFO (qtp1758624125-2269) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 358765 INFO (qtp1758624125-2269) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1631b147
[junit4] 2> 358768 INFO (qtp1758624125-2269) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@64085935[collection1] main]
[junit4] 2> 358768 INFO (qtp1758624125-2269) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 358768 INFO (searcherExecutor-1012-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@64085935[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1)))}
[junit4] 2> 358768 INFO (qtp1758624125-2269) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 358769 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 358771 INFO (qtp1758624125-2270) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 358771 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 3
[junit4] 2> 358771 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1476162944888
[junit4] 2> 358771 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 2
[junit4] 2> 358771 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 358772 INFO (qtp1758624125-2270) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0
[junit4] 2> 358773 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 7
[junit4] 2> 358776 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=612816853, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 358777 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 358777 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@5a805155 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6c41bb8a; maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 358777 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 358777 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 358777 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 358777 INFO (qtp1758624125-2266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358779 INFO (qtp1758624125-2264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358781 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=_1.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358782 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=3&qt=/replication&file=segments_3&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 358783 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=1972) : 0 secs (null bytes/sec) to MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@5a805155 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6c41bb8a; maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 358784 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics
[junit4] 2> java.io.IOException: file "replication.properties" was already written to
[junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:701)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:513)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 358785 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=97.3955078125, floorSegmentMB=1.9404296875, forceMergeDeletesPctAllowed=19.883416600001897, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7863546212186346
[junit4] 2> 358785 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 358786 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4ace985e[collection1] main]
[junit4] 2> 358786 INFO (searcherExecutor-1022-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4ace985e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1)))}
[junit4] 2> 358786 INFO (qtp546545157-2281) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:54189/solr/collection1/replication&command=fetchindex} status=0 QTime=17
[junit4] 2> 358788 INFO (qtp546545157-2283) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty2&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 358788 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 358789 INFO (qtp1758624125-2269) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 358790 INFO (qtp1758624125-2271) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 358792 INFO (qtp1758624125-2270) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 358792 INFO (qtp546545157-2284) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1
[junit4] 2> 358793 INFO (qtp1758624125-2266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 358793 INFO (qtp546545157-2285) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 358795 INFO (qtp1758624125-2266) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 1
[junit4] 2> 358796 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 358796 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1631b147
[junit4] 2> 358799 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7ee76ded[collection1] realtime]
[junit4] 2> 358799 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 358799 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={openSearcher=false&commit=true&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 358800 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 358802 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 358802 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 4
[junit4] 2> 358802 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1476162944919
[junit4] 2> 358802 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 3
[junit4] 2> 358802 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 358803 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0
[junit4] 2> 358804 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 358806 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=612816853, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 358806 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 358806 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 358806 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 359814 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 359814 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 360820 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 360820 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 361821 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 361821 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 362823 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 362823 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 363824 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 363824 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 364834 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 364834 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 365837 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 365837 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 366868 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 366869 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 367871 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 367871 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 368875 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Found unused file: segments_2
[junit4] 2> 368875 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Sleeping for 1000ms to wait for unused lucene index files to be delete-able
[junit4] 2> 369878 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher IndexFetcher slept for 11000ms for unused lucene index files to be delete-able
[junit4] 2> 369878 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@dfc0a8e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1bcfff44; maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 369878 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 369878 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 369879 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 369879 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
[junit4] 2> 369879 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
[junit4] 2> 369879 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 369881 INFO (qtp1758624125-2271) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 369883 INFO (qtp1758624125-2270) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 369884 INFO (qtp1758624125-2264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=_2.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 369886 INFO (qtp1758624125-2266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=4&qt=/replication&file=segments_4&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 369886 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2035) : 11 secs (185 bytes/sec) to MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@dfc0a8e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1bcfff44; maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 369887 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics
[junit4] 2> java.io.IOException: file "replication.properties" was already written to
[junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:701)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:513)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 369888 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=97.3955078125, floorSegmentMB=1.9404296875, forceMergeDeletesPctAllowed=19.883416600001897, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7863546212186346
[junit4] 2> 369889 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 369891 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@65b6d2c5[collection1] main]
[junit4] 2> 369892 INFO (searcherExecutor-1022-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@65b6d2c5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)))}
[junit4] 2> 369892 INFO (qtp546545157-2285) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:54189/solr/collection1/replication&command=fetchindex} status=0 QTime=11092
[junit4] 2> 369897 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 2> 369897 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 0 docs
[junit4] 2> 369899 INFO (qtp546545157-2288) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:empty3&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 369900 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 369901 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 369902 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 369902 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1631b147
[junit4] 2> 369906 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@769013b0[collection1] main]
[junit4] 2> 369906 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 369906 INFO (searcherExecutor-1012-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@769013b0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1) Uninverting(_3(7.0.0):c1)))}
[junit4] 2> 369906 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={commit=true&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 369907 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 369909 INFO (qtp1758624125-2271) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 369909 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 5
[junit4] 2> 369909 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1476162956025
[junit4] 2> 369909 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 4
[junit4] 2> 369909 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 369910 INFO (qtp1758624125-2270) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&wt=javabin&version=2&command=filelist} status=0 QTime=0
[junit4] 2> 369910 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 13
[junit4] 2> 369913 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=612816853, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download (fullCopy=false) to MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@4822c3ef lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c6ca0c5; maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfe because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.si because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _0.cfs because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfs because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.cfe because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _1.si because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.si because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.cfe because it already exists
[junit4] 2> 369914 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Skipping download for _2.cfs because it already exists
[junit4] 2> 369915 INFO (qtp1758624125-2264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 369916 INFO (qtp1758624125-2266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.cfs&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 369918 INFO (qtp1758624125-2269) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=_3.cfe&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 369919 INFO (qtp1758624125-2267) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={generation=5&qt=/replication&file=segments_5&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0
[junit4] 2> 369919 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download (fullCopy=false,bytesDownloaded=2098) : 0 secs (null bytes/sec) to MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@4822c3ef lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2c6ca0c5; maxCacheMB=0.908203125 maxMergeSizeMB=0.03125))
[junit4] 2> 369921 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Exception while updating statistics
[junit4] 2> java.io.IOException: file "replication.properties" was already written to
[junit4] 2> at org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:654)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.logReplicationTimeAndConfFiles(IndexFetcher.java:701)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:513)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 369921 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=97.3955078125, floorSegmentMB=1.9404296875, forceMergeDeletesPctAllowed=19.883416600001897, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7863546212186346
[junit4] 2> 369922 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 369924 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@9a4665c[collection1] main]
[junit4] 2> 369925 INFO (searcherExecutor-1022-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@9a4665c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1) Uninverting(_3(7.0.0):c1)))}
[junit4] 2> 369925 INFO (qtp546545157-2287) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:54189/solr/collection1/replication&command=fetchindex} status=0 QTime=18
[junit4] 2> 369927 INFO (qtp1758624125-2271) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 369927 INFO (qtp1758624125-2268) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} hits=4 status=0 QTime=0
[junit4] 2> 369927 INFO (indexFetcher-1026-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 5
[junit4] 2> 369927 INFO (indexFetcher-1026-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 1476162956025
[junit4] 2> 369927 INFO (indexFetcher-1026-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 5
[junit4] 2> 369927 INFO (indexFetcher-1026-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave in sync with master.
[junit4] 2> 369927 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
[junit4] 2> 369928 INFO (qtp546545157-2281) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=name:(empty1+empty2+empty3+empty4)&sort=id+desc&wt=javabin&version=2} hits=4 status=0 QTime=0
[junit4] 2> 369929 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 4 docs
[junit4] 2> 369929 INFO (qtp1758624125-2271) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 369930 INFO (qtp1758624125-2264) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 369931 INFO (qtp1758624125-2266) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 369932 INFO (qtp546545157-2283) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=1
[junit4] 2> 369933 INFO (qtp1758624125-2269) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 369933 INFO (qtp546545157-2284) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 369934 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEmptyCommits
[junit4] 2> 369935 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@5879c9b6{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 369935 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1012943509
[junit4] 2> 369936 INFO (coreCloseExecutor-1031-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@59749d91
[junit4] 2> 369938 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@45b4fdd7{/solr,null,UNAVAILABLE}
[junit4] 2> 369940 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@752d2a29{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 369940 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1904598744
[junit4] 2> 369941 INFO (coreCloseExecutor-1033-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4b3ba175
[junit4] 2> 369944 INFO (TEST-TestReplicationHandler.testEmptyCommits-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@da9fd28{/solr,null,UNAVAILABLE}
[junit4] 2> 369947 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestHandlerPathUnchanged
[junit4] 2> 369947 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-003/collection1
[junit4] 2> 369952 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 369956 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@30539e2c{/solr,null,AVAILABLE}
[junit4] 2> 369957 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@4ef7235d{HTTP/1.1,[http/1.1]}{127.0.0.1:54223}
[junit4] 2> 369957 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server Started @375199ms
[junit4] 2> 369957 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-003/collection1/data, hostContext=/solr, hostPort=54223}
[junit4] 2> 369958 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 369958 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 369958 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 369958 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-11T05:15:56.081Z
[junit4] 2> 369958 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-003/solr.xml
[junit4] 2> 369970 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-003/.
[junit4] 2> 369970 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 369978 WARN (coreLoadExecutor-1040-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 369979 INFO (coreLoadExecutor-1040-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 369993 INFO (coreLoadExecutor-1040-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 369994 INFO (coreLoadExecutor-1040-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 369995 INFO (coreLoadExecutor-1040-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-003/./collection1
[junit4] 2> 369995 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-003/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-003/./collection1/data/]
[junit4] 2> 369996 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.11239653555112072]
[junit4] 2> 370004 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 370004 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 370004 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=95.794921875, floorSegmentMB=0.4208984375, forceMergeDeletesPctAllowed=8.115832711559346, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 370004 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4142d3ad[collection1] main]
[junit4] 2> 370005 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-003/collection1/conf
[junit4] 2> 370005 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 370005 INFO (coreLoadExecutor-1040-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 370005 INFO (searcherExecutor-1041-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4142d3ad[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 370483 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-004/collection1
[junit4] 2> 370488 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 370491 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7aa5eb1{/solr,null,AVAILABLE}
[junit4] 2> 370491 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@23c088b1{HTTP/1.1,[http/1.1]}{127.0.0.1:54224}
[junit4] 2> 370491 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server Started @375733ms
[junit4] 2> 370491 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-004/collection1/data, hostContext=/solr, hostPort=54224}
[junit4] 2> 370492 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 370492 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 370492 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 370492 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-11T05:15:56.615Z
[junit4] 2> 370492 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-004/solr.xml
[junit4] 2> 370517 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-004/.
[junit4] 2> 370517 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 370531 WARN (coreLoadExecutor-1050-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 370531 INFO (coreLoadExecutor-1050-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 370537 INFO (coreLoadExecutor-1050-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 370540 INFO (coreLoadExecutor-1050-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 370543 INFO (coreLoadExecutor-1050-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-004/./collection1
[junit4] 2> 370543 INFO (coreLoadExecutor-1050-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-004/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-004/./collection1/data/]
[junit4] 2> 370544 INFO (coreLoadExecutor-1050-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.11239653555112072]
[junit4] 2> 370554 INFO (coreLoadExecutor-1050-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 370554 INFO (coreLoadExecutor-1050-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 370554 INFO (coreLoadExecutor-1050-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=95.794921875, floorSegmentMB=0.4208984375, forceMergeDeletesPctAllowed=8.115832711559346, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 370554 INFO (coreLoadExecutor-1050-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@799ff9e3[collection1] main]
[junit4] 2> 370555 INFO (coreLoadExecutor-1050-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-004/collection1/conf
[junit4] 2> 370556 INFO (coreLoadExecutor-1050-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 370556 INFO (searcherExecutor-1051-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@799ff9e3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 371021 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestHandlerPathUnchanged
[junit4] 2> 371022 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@4ef7235d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 371022 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1368414730
[junit4] 2> 371023 INFO (coreCloseExecutor-1056-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2832e623
[junit4] 2> 371025 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@30539e2c{/solr,null,UNAVAILABLE}
[junit4] 2> 371027 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@23c088b1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 371027 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1714044272
[junit4] 2> 371028 INFO (coreCloseExecutor-1058-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6ef141a5
[junit4] 2> 371030 INFO (TEST-TestReplicationHandler.doTestHandlerPathUnchanged-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7aa5eb1{/solr,null,UNAVAILABLE}
[junit4] 2> 371033 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterStartupWithNoActivity
[junit4] 2> 371034 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-005/collection1
[junit4] 2> 371039 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 371041 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@79a1ad45{/solr,null,AVAILABLE}
[junit4] 2> 371041 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@6d3d72f7{HTTP/1.1,[http/1.1]}{127.0.0.1:54225}
[junit4] 2> 371041 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server Started @376283ms
[junit4] 2> 371041 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-005/collection1/data, hostContext=/solr, hostPort=54225}
[junit4] 2> 371042 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 371042 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 371042 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 371042 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-11T05:15:57.165Z
[junit4] 2> 371042 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-005/solr.xml
[junit4] 2> 371051 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-005/.
[junit4] 2> 371051 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 371058 WARN (coreLoadExecutor-1065-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 371059 INFO (coreLoadExecutor-1065-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 371066 INFO (coreLoadExecutor-1065-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 371067 INFO (coreLoadExecutor-1065-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 371068 INFO (coreLoadExecutor-1065-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-005/./collection1
[junit4] 2> 371069 INFO (coreLoadExecutor-1065-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-005/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-005/./collection1/data/]
[junit4] 2> 371070 INFO (coreLoadExecutor-1065-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.11239653555112072]
[junit4] 2> 371078 INFO (coreLoadExecutor-1065-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 371078 INFO (coreLoadExecutor-1065-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 371078 INFO (coreLoadExecutor-1065-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=95.794921875, floorSegmentMB=0.4208984375, forceMergeDeletesPctAllowed=8.115832711559346, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 371078 INFO (coreLoadExecutor-1065-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a9f2da4[collection1] main]
[junit4] 2> 371079 INFO (coreLoadExecutor-1065-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-005/collection1/conf
[junit4] 2> 371079 INFO (coreLoadExecutor-1065-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 371079 INFO (coreLoadExecutor-1065-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 371079 INFO (searcherExecutor-1066-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@a9f2da4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 371559 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-006/collection1
[junit4] 2> 371564 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 371566 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@47db7f01{/solr,null,AVAILABLE}
[junit4] 2> 371566 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@56238302{HTTP/1.1,[http/1.1]}{127.0.0.1:54226}
[junit4] 2> 371566 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.e.j.s.Server Started @376808ms
[junit4] 2> 371566 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-006/collection1/data, hostContext=/solr, hostPort=54226}
[junit4] 2> 371567 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 371567 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 371567 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 371567 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-11T05:15:57.690Z
[junit4] 2> 371567 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-006/solr.xml
[junit4] 2> 371589 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-006/.
[junit4] 2> 371589 INFO (TEST-TestReplicationHandler.doTestReplicateAfterStartupWithNoActivity-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 371596 WARN (coreLoadExecutor-1075-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 371597 INFO (coreLoadExecutor-1075-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 371604 INFO (coreLoadExecutor-1075-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 371605 INFO (coreLoadExecutor-1075-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 371606 INFO (coreLoadExecutor-1075-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-006/./collection1
[junit4] 2> 371607 INFO (coreLoadExecutor-1075-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-006/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_976C4812E95EBCC-001/solr-instance-006/./collection1/data/]
[junit4] 2> 371608 INFO (coreLoadExecutor-1075-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.11239653555112072]
[junit4] 2> 371616 INFO (coreLoadExecutor-1075-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 371616 INFO (coreLoadExecutor-1075-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 371616 INFO (coreLoadExecutor-1075-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePo
[...truncated too long message...]
1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5387a358[collection1] main]
[junit4] 2> 423439 INFO (qtp1069260691-3273) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 423440 INFO (searcherExecutor-1656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5387a358[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1)))}
[junit4] 2> 423440 INFO (qtp1069260691-3273) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
[junit4] 2> 423440 INFO (qtp1069260691-3272) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[554]} 0 0
[junit4] 2> 423441 INFO (qtp1069260691-3274) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 423441 INFO (qtp1069260691-3274) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@23cc5c36
[junit4] 2> 423443 INFO (qtp1069260691-3274) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@68ac1bab[collection1] main]
[junit4] 2> 423443 INFO (qtp1069260691-3274) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 423443 INFO (searcherExecutor-1656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@68ac1bab[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1) Uninverting(_3(7.0.0):c1)))}
[junit4] 2> 423443 INFO (qtp1069260691-3274) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
[junit4] 2> 423444 INFO (qtp1069260691-3275) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[555]} 0 0
[junit4] 2> 423444 INFO (qtp1069260691-3276) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 423444 INFO (qtp1069260691-3276) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@23cc5c36
[junit4] 2> 423446 INFO (qtp1069260691-3276) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a8f0910[collection1] main]
[junit4] 2> 423446 INFO (qtp1069260691-3276) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 423446 INFO (searcherExecutor-1656-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a8f0910[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c1) Uninverting(_1(7.0.0):c1) Uninverting(_2(7.0.0):c1) Uninverting(_3(7.0.0):c1) Uninverting(_4(7.0.0):c1)))}
[junit4] 2> 423446 INFO (qtp1069260691-3276) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
[junit4] 2> 423447 INFO (qtp1069260691-3277) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=id:555&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 423447 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC]) [ ] o.a.s.h.TestReplicationHandler Waited for 0ms and found 1 docs
[junit4] 2> 423448 INFO (qtp1387224089-3260) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=enablereplication} status=0 QTime=0
[junit4] 2> 423449 INFO (qtp1069260691-3270) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={command=fetchindex} status=0 QTime=0
[junit4] 2> 423450 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestReplicateAfterWrite2Slave
[junit4] 2> 423452 ERROR (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master at: http://127.0.0.1:54402/solr/collection1 is not available. Index fetch failed. Exception: Error from server at http://127.0.0.1:54402/solr/collection1: Expected mime type application/octet-stream but got text/html. <html>
[junit4] 2> <head>
[junit4] 2> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
[junit4] 2> <title>Error 503 </title>
[junit4] 2> </head>
[junit4] 2> <body>
[junit4] 2> <h2>HTTP ERROR: 503</h2>
[junit4] 2> <p>Problem accessing /solr/collection1/replication. Reason:
[junit4] 2> <pre> Service Unavailable</pre></p>
[junit4] 2> <hr /><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.3.8.v20160314</a><hr/>
[junit4] 2> </body>
[junit4] 2> </html>
[junit4] 2>
[junit4] 2> 423453 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@314da7b1{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 423453 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1145242557
[junit4] 2> 423454 INFO (coreCloseExecutor-1661-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@32a32ba0
[junit4] 2> 423456 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@153a28e5{/solr,null,UNAVAILABLE}
[junit4] 2> 423458 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@16d20e9c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 423458 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=253486905
[junit4] 2> 423459 INFO (coreCloseExecutor-1663-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2da2535b
[junit4] 2> 423460 INFO (TEST-TestReplicationHandler.doTestReplicateAfterWrite2Slave-seed#[976C4812E95EBCC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@35d979a2{/solr,null,UNAVAILABLE}
[junit4] 2> 423462 INFO (SUITE-TestReplicationHandler-seed#[976C4812E95EBCC]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {name=PostingsFormat(name=Asserting), id=PostingsFormat(name=Asserting), newname=PostingsFormat(name=Asserting)}, docValues:{}, maxPointsInLeafNode=788, maxMBSortInHeap=7.097413682666291, sim=ClassicSimilarity, locale=und, timezone=America/Indiana/Indianapolis
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=165528136,total=286785536
[junit4] 2> NOTE: All tests run in this JVM: [TestSort, DistributedDebugComponentTest, TestSolr4Spatial2, TestSchemaNameResource, TestSolrDeletionPolicy1, TriLevelCompositeIdRoutingTest, TestManagedStopFilterFactory, TestSimpleQParserPlugin, TestJsonRequest, IndexSchemaRuntimeFieldTest, TestRandomCollapseQParserPlugin, MigrateRouteKeyTest, CollectionsAPIAsyncDistributedZkTest, TestDownShardTolerantSearch, TestSubQueryTransformerDistrib, TestCloudPivotFacet, SegmentsInfoRequestHandlerTest, TestStandardQParsers, TestHighlightDedupGrouping, TestExactSharedStatsCache, TestRequestStatusCollectionAPI, DirectUpdateHandlerTest, TestLockTree, TestMergePolicyConfig, TestExceedMaxTermLength, RecoveryZkTest, TestReplicationHandler]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandler -Dtests.seed=976C4812E95EBCC -Dtests.slow=true -Dtests.locale=und -Dtests.timezone=America/Indiana/Indianapolis -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestReplicationHandler (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory]
[junit4] > org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] > at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:43)
[junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] > at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386)
[junit4] > at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:268)
[junit4] > at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:397)
[junit4] > at org.apache.solr.handler.ReplicationHandler.lambda$handleRequestBody$0(ReplicationHandler.java:279)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([976C4812E95EBCC]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:261)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [81/635 (1!)] on J0 in 97.11s, 15 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 62121 lines...]
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 3599
- Failure!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/3599/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.handler.TestSQLHandlerNonCloud.testSQLHandler
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([8EB9050778CE4C6C:2AE26019728957B3]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at org.apache.solr.handler.TestSQLHandlerNonCloud.testSQLHandler(TestSQLHandlerNonCloud.java:61)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestSQLHandlerNonCloud
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.handler.TestSQLHandlerNonCloud: 1) Thread[id=9034, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestSQLHandlerNonCloud] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.TestSQLHandlerNonCloud:
1) Thread[id=9034, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestSQLHandlerNonCloud]
at java.lang.Thread.sleep(Native Method)
at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([8EB9050778CE4C6C]:0)
Build Log:
[...truncated 11369 lines...]
[junit4] Suite: org.apache.solr.handler.TestSQLHandlerNonCloud
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandlerNonCloud_8EB9050778CE4C6C-001/init-core-data-001
[junit4] 2> 1499867 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1499879 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1499879 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1499879 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandlerNonCloud_8EB9050778CE4C6C-001/tempDir-002/cores/core
[junit4] 2> 1499881 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1499883 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4456e630{/solr,null,AVAILABLE}
[junit4] 2> 1499884 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.e.j.s.ServerConnector Started ServerConnector@15a49828{HTTP/1.1,[http/1.1]}{127.0.0.1:50167}
[junit4] 2> 1499885 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.e.j.s.Server Started @1505061ms
[junit4] 2> 1499885 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50167, configSetBaseDir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandlerNonCloud_8EB9050778CE4C6C-001/tempDir-001, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandlerNonCloud_8EB9050778CE4C6C-001/tempDir-002/cores}
[junit4] 2> 1499885 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.0.0
[junit4] 2> 1499885 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1499885 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1499885 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-11T19:20:19.555Z
[junit4] 2> 1499885 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandlerNonCloud_8EB9050778CE4C6C-001/tempDir-001/solr.xml
[junit4] 2> 1499918 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandlerNonCloud_8EB9050778CE4C6C-001/tempDir-002/cores
[junit4] 2> 1499918 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1499960 WARN (coreLoadExecutor-4389-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1499961 INFO (coreLoadExecutor-4389-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 1499981 INFO (coreLoadExecutor-4389-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1500052 WARN (coreLoadExecutor-4389-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 1500054 INFO (coreLoadExecutor-4389-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1500063 INFO (coreLoadExecutor-4389-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandlerNonCloud_8EB9050778CE4C6C-001/tempDir-001/collection1
[junit4] 2> 1500064 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandlerNonCloud_8EB9050778CE4C6C-001/tempDir-001/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandlerNonCloud_8EB9050778CE4C6C-001/tempDir-002/cores/core/data/]
[junit4] 2> 1500064 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@700a2a32
[junit4] 2> 1500064 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8050785153887676]
[junit4] 2> 1500079 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1500080 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1500080 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1500080 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1500081 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=11.615234375, floorSegmentMB=1.939453125, forceMergeDeletesPctAllowed=29.54695552477502, segmentsPerTier=39.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1500081 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@19f93f05[collection1] main]
[junit4] 2> 1500081 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1500087 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1500128 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1500128 INFO (searcherExecutor-4390-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1500129 INFO (coreLoadExecutor-4389-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547922172594356224
[junit4] 2> 1500131 INFO (searcherExecutor-4390-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 1500131 INFO (searcherExecutor-4390-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 1500131 INFO (searcherExecutor-4390-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 1500132 INFO (searcherExecutor-4390-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 1500132 INFO (searcherExecutor-4390-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 1500132 INFO (searcherExecutor-4390-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 1500133 INFO (searcherExecutor-4390-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 1500133 INFO (searcherExecutor-4390-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 1500134 INFO (searcherExecutor-4390-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@19f93f05[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1500455 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.SolrJettyTestBase Jetty Assigned Port#50167
[junit4] 2> 1500460 INFO (TEST-TestSQLHandlerNonCloud.testSQLHandler-seed#[8EB9050778CE4C6C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSQLHandler
[junit4] 2> 1501704 INFO (qtp63078093-9018) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/sql params={wt=json&version=2.2&stmt=select+id,+field_i,+str_s+from+collection1+limit+10} status=0 QTime=1227
[junit4] 2> 1501709 WARN (qtp63078093-9018-SendThread(127.0.0.1:49998)) [ x:collection1] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 1502819 WARN (qtp63078093-9018-SendThread(127.0.0.1:49998)) [ x:collection1] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 1503934 WARN (qtp63078093-9018-SendThread(127.0.0.1:49998)) [ x:collection1] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 1505042 WARN (qtp63078093-9018-SendThread(127.0.0.1:49998)) [ x:collection1] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 1506147 WARN (qtp63078093-9018-SendThread(127.0.0.1:49998)) [ x:collection1] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 1507253 WARN (qtp63078093-9018-SendThread(127.0.0.1:49998)) [ x:collection1] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 1508356 WARN (qtp63078093-9018-SendThread(127.0.0.1:49998)) [ x:collection1] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 1509465 WARN (qtp63078093-9018-SendThread(127.0.0.1:49998)) [ x:collection1] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 1510571 WARN (qtp63078093-9018-SendThread(127.0.0.1:49998)) [ x:collection1] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 1511685 WARN (qtp63078093-9018-SendThread(127.0.0.1:49998)) [ x:collection1] o.a.z.ClientCnxn Session 0x0 for server null, unexpected error, closing socket connection and attempting reconnect
[junit4] 2> java.net.ConnectException: Connection refused
[junit4] 2> at sun.nio.ch.SocketChannelImpl.checkConnect(Native Method)
[junit4] 2> at sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:717)
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:361)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081)
[junit4] 2> 1512896 ERROR (qtp63078093-9018) [ x:collection1] o.a.s.c.s.i.s.ExceptionStream org.apache.solr.common.SolrException: java.util.concurrent.TimeoutException: Could not connect to ZooKeeper 127.0.0.1:49998/solr within 10000 ms
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:182)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:116)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:106)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.<init>(ZkStateReader.java:226)
[junit4] 2> at org.apache.solr.client.solrj.impl.CloudSolrClient.connect(CloudSolrClient.java:563)
[junit4] 2> at org.apache.solr.client.solrj.io.SolrClientCache.getCloudSolrClient(SolrClientCache.java:50)
[junit4] 2> at org.apache.solr.client.solrj.io.stream.CloudSolrStream.open(CloudSolrStream.java:298)
[junit4] 2> at org.apache.solr.handler.SQLHandler$LimitStream.open(SQLHandler.java:1325)
[junit4] 2> at org.apache.solr.client.solrj.io.stream.SelectStream.open(SelectStream.java:184)
[junit4] 2> at org.apache.solr.client.solrj.io.stream.ExceptionStream.open(ExceptionStream.java:51)
[junit4] 2> at org.apache.solr.handler.StreamHandler$TimerStream.open(StreamHandler.java:437)
[junit4] 2> at org.apache.solr.response.TextResponseWriter.writeTupleStream(TextResponseWriter.java:308)
[junit4] 2> at org.apache.solr.response.TextResponseWriter.writeVal(TextResponseWriter.java:169)
[junit4] 2> at org.apache.solr.response.JSONWriter.writeNamedListAsMapWithDups(JSONResponseWriter.java:185)
[junit4] 2> at org.apache.solr.response.JSONWriter.writeNamedList(JSONResponseWriter.java:301)
[junit4] 2> at org.apache.solr.response.JSONWriter.writeResponse(JSONResponseWriter.java:97)
[junit4] 2> at org.apache.solr.response.JSONResponseWriter.write(JSONResponseWriter.java:55)
[junit4] 2> at org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:65)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.writeResponse(HttpSolrCall.java:728)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:469)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:302)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:253)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:111)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1676)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:581)
[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:1160)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
[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:1092)
[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:399)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:518)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:244)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:246)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:156)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.util.concurrent.TimeoutException: Could not connect to ZooKeeper 127.0.0.1:49998/solr within 10000 ms
[junit4] 2> at org.apache.solr.common.cloud.ConnectionManager.waitForConnected(ConnectionManager.java:233)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.<init>(SolrZkClient.java:174)
[junit4] 2> ... 44 more
[junit4] 2>
[junit4] 2> 1512898 INFO (TEST-TestSQLHandlerNonCloud.testSQLHandler-seed#[8EB9050778CE4C6C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSQLHandler
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSQLHandlerNonCloud -Dtests.method=testSQLHandler -Dtests.seed=8EB9050778CE4C6C -Dtests.slow=true -Dtests.locale=hr-HR -Dtests.timezone=America/Asuncion -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 12.4s J1 | TestSQLHandlerNonCloud.testSQLHandler <<<
[junit4] > Throwable #1: java.lang.AssertionError
[junit4] > at __randomizedtesting.SeedInfo.seed([8EB9050778CE4C6C:2AE26019728957B3]:0)
[junit4] > at org.apache.solr.handler.TestSQLHandlerNonCloud.testSQLHandler(TestSQLHandlerNonCloud.java:61)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1512902 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@15a49828{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1512902 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1037520374
[junit4] 2> 1512903 INFO (coreCloseExecutor-4395-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3492fe7f
[junit4] 2> 1512915 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4456e630{/solr,null,UNAVAILABLE}
[junit4] 2> 1512917 INFO (SUITE-TestSQLHandlerNonCloud-seed#[8EB9050778CE4C6C]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.handler.TestSQLHandlerNonCloud_8EB9050778CE4C6C-001
[junit4] 2> Oct 11, 2016 7:20:32 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> Oct 11, 2016 7:20:52 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.handler.TestSQLHandlerNonCloud:
[junit4] 2> 1) Thread[id=9034, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestSQLHandlerNonCloud]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Oct 11, 2016 7:20:52 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=9034, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestSQLHandlerNonCloud]
[junit4] 2> Oct 11, 2016 7:20:52 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1382, maxMBSortInHeap=5.779938140791276, sim=RandomSimilarity(queryNorm=false): {}, locale=hr-HR, timezone=America/Asuncion
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=3,threads=1,free=292897808,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [BadCopyFieldTest, MultiThreadedOCPTest, TestManagedStopFilterFactory, TestDistributedGrouping, TestFoldingMultitermQuery, TestPKIAuthenticationPlugin, LeaderElectionIntegrationTest, TestRealTimeGet, DeleteShardTest, ConjunctionSolrSpellCheckerTest, CdcrBootstrapTest, PeerSyncReplicationTest, ExplicitHLLTest, OverriddenZkACLAndCredentialsProvidersTest, TestBackupRepositoryFactory, TestBulkSchemaAPI, DistributedFacetPivotLongTailTest, TestExactSharedStatsCache, CachingDirectoryFactoryTest, DistributedQueryComponentCustomSortTest, OpenCloseCoreStressTest, TestSchemaSimilarityResource, TestLRUStatsCache, ConfigSetsAPITest, RollingRestartTest, TestLuceneMatchVersion, TestUtils, TestSimpleTrackingShardHandler, DataDrivenBlockJoinTest, SuggesterTSTTest, SuggestComponentTest, DistribJoinFromCollectionTest, TestLegacyNumericRangeQueryBuilder, CreateCollectionCleanupTest, TestManagedSchema, ChangedSchemaMergeTest, AtomicUpdatesTest, DistributedFacetExistsSmallTest, HttpPartitionTest, TestRebalanceLeaders, CacheHeaderTest, BadIndexSchemaTest, SolrCmdDistributorTest, TestBlendedInfixSuggestions, IndexSchemaTest, TestUninvertingReader, TestSSLRandomization, TermVectorComponentTest, ZkStateWriterTest, HdfsTlogReplayBufferedWhileIndexingTest, SimpleMLTQParserTest, TestConfigSetsAPI, TestLMJelinekMercerSimilarityFactory, TestGraphMLResponseWriter, TestLocalFSCloudBackupRestore, TestSystemIdResolver, BlockJoinFacetDistribTest, CdcrReplicationHandlerTest, TestCoreDiscovery, TestCloudManagedSchema, TestCodecSupport, TestRandomFaceting, TestImplicitCoreProperties, CursorMarkTest, SharedFSAutoReplicaFailoverTest, TestMiniSolrCloudCluster, TestReversedWildcardFilterFactory, BasicZkTest, TestRangeQuery, HdfsWriteToMultipleCollectionsTest, TestExclusionRuleCollectionAccess, FieldAnalysisRequestHandlerTest, PreAnalyzedFieldTest, HdfsLockFactoryTest, NotRequiredUniqueKeyTest, TestFieldCache, ConvertedLegacyTest, RuleEngineTest, TestStressCloudBlindAtomicUpdates, TestLeaderInitiatedRecoveryThread, LoggingHandlerTest, UUIDFieldTest, UpdateParamsTest, TestConfigSetImmutable, TestRestoreCore, TestSolr4Spatial, TestUseDocValuesAsStored, TestQuerySenderListener, CleanupOldIndexTest, EchoParamsTest, ResourceLoaderTest, TestStressRecovery, HdfsChaosMonkeySafeLeaderTest, BlockJoinFacetSimpleTest, CircularListTest, TestOverriddenPrefixQueryForCustomFieldType, TestFieldCollectionResource, TestCopyFieldCollectionResource, CurrencyFieldOpenExchangeTest, SliceStateTest, TestNumericTerms32, SegmentsInfoRequestHandlerTest, BlockCacheTest, CloneFieldUpdateProcessorFactoryTest, RequestLoggingTest, SpellingQueryConverterTest, SaslZkACLProviderTest, TlogReplayBufferedWhileIndexingTest, AnalyticsMergeStrategyTest, TestSQLHandlerNonCloud]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSQLHandlerNonCloud -Dtests.seed=8EB9050778CE4C6C -Dtests.slow=true -Dtests.locale=hr-HR -Dtests.timezone=America/Asuncion -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J1 | TestSQLHandlerNonCloud (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.TestSQLHandlerNonCloud:
[junit4] > 1) Thread[id=9034, name=Connection evictor, state=TIMED_WAITING, group=TGRP-TestSQLHandlerNonCloud]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at __randomizedtesting.SeedInfo.seed([8EB9050778CE4C6C]:0)
[junit4] Completed [246/636 (1!)] on J1 in 33.18s, 1 test, 1 failure, 1 error <<< FAILURES!
[...truncated 54283 lines...]
-ecj-javadoc-lint-src:
[mkdir] Created dir: /var/folders/qg/h2dfw5s161s51l2bn79mrb7r0000gn/T/ecj282822199
[ecj-lint] Compiling 988 source files to /var/folders/qg/h2dfw5s161s51l2bn79mrb7r0000gn/T/ecj282822199
[ecj-lint] invalid Class-Path header in manifest of jar file: /Users/jenkins/.ivy2/cache/org.restlet.jee/org.restlet/jars/org.restlet-2.3.0.jar
[ecj-lint] invalid Class-Path header in manifest of jar file: /Users/jenkins/.ivy2/cache/org.restlet.jee/org.restlet.ext.servlet/jars/org.restlet.ext.servlet-2.3.0.jar
[ecj-lint] ----------
[ecj-lint] 1. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/cloud/Assign.java (at line 101)
[ecj-lint] Collections.sort(shardIdNames, (o1, o2) -> {
[ecj-lint] ^^^^^^^^^^^
[ecj-lint] (Recovered) Internal inconsistency detected during lambda shape analysis
[ecj-lint] ----------
[ecj-lint] 2. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/cloud/Assign.java (at line 101)
[ecj-lint] Collections.sort(shardIdNames, (o1, o2) -> {
[ecj-lint] ^^^^^^^^^^^
[ecj-lint] (Recovered) Internal inconsistency detected during lambda shape analysis
[ecj-lint] ----------
[ecj-lint] 3. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/cloud/Assign.java (at line 101)
[ecj-lint] Collections.sort(shardIdNames, (o1, o2) -> {
[ecj-lint] ^^^^^^^^^^^
[ecj-lint] (Recovered) Internal inconsistency detected during lambda shape analysis
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 4. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/cloud/rule/ReplicaAssigner.java (at line 216)
[ecj-lint] Collections.sort(sortedLiveNodes, (n1, n2) -> {
[ecj-lint] ^^^^^^^^^^^
[ecj-lint] (Recovered) Internal inconsistency detected during lambda shape analysis
[ecj-lint] ----------
[ecj-lint] 5. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/cloud/rule/ReplicaAssigner.java (at line 216)
[ecj-lint] Collections.sort(sortedLiveNodes, (n1, n2) -> {
[ecj-lint] ^^^^^^^^^^^
[ecj-lint] (Recovered) Internal inconsistency detected during lambda shape analysis
[ecj-lint] ----------
[ecj-lint] 6. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/cloud/rule/ReplicaAssigner.java (at line 216)
[ecj-lint] Collections.sort(sortedLiveNodes, (n1, n2) -> {
[ecj-lint] ^^^^^^^^^^^
[ecj-lint] (Recovered) Internal inconsistency detected during lambda shape analysis
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 7. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/core/HdfsDirectoryFactory.java (at line 227)
[ecj-lint] dir = new BlockDirectory(path, hdfsDir, cache, null, blockCacheReadEnabled, false, cacheMerges, cacheReadOnce);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'dir' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 8. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/handler/AnalysisRequestHandlerBase.java (at line 120)
[ecj-lint] reader = cfiltfac.create(reader);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'reader' is not closed at this location
[ecj-lint] ----------
[ecj-lint] 9. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/handler/AnalysisRequestHandlerBase.java (at line 144)
[ecj-lint] return namedList;
[ecj-lint] ^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'listBasedTokenStream' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 10. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/handler/ClassifyStream.java (at line 88)
[ecj-lint] SolrCore solrCore = (SolrCore) solrCoreObj;
[ecj-lint] ^^^^^^^^
[ecj-lint] Resource leak: 'solrCore' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 11. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/handler/ReplicationHandler.java (at line 1252)
[ecj-lint] DirectoryReader reader = s==null ? null : s.get().getIndexReader();
[ecj-lint] ^^^^^^
[ecj-lint] Resource leak: 'reader' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 12. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/handler/SQLHandler.java (at line 258)
[ecj-lint] ParallelStream parallelStream = new ParallelStream(workerZkHost, workerCollection, tupleStream, numWorkers, comp);
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'parallelStream' is never closed
[ecj-lint] ----------
[ecj-lint] 13. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/handler/SQLHandler.java (at line 397)
[ecj-lint] ParallelStream parallelStream = new ParallelStream(workerZkHost, workerCollection, tupleStream, numWorkers, comp);
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'parallelStream' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 14. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/handler/component/ExpandComponent.java (at line 223)
[ecj-lint] UninvertingReader uninvertingReader = new UninvertingReader(new ReaderWrapper(searcher.getSlowAtomicReader(), field), mapping);
[ecj-lint] ^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'uninvertingReader' is never closed
[ecj-lint] ----------
[ecj-lint] 15. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/handler/component/ExpandComponent.java (at line 383)
[ecj-lint] UninvertingReader uninvertingReader = new UninvertingReader(new ReaderWrapper(searcher.getSlowAtomicReader(), field), mapping);
[ecj-lint] ^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'uninvertingReader' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 16. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/highlight/DefaultSolrHighlighter.java (at line 544)
[ecj-lint] tvWindowStream = new OffsetWindowTokenFilter(tvStream);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'tvWindowStream' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 17. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/request/SimpleFacets.java (at line 857)
[ecj-lint] fastForRandomSet = new HashDocSet(sset.getDocs(), 0, sset.size());
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'fastForRandomSet' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 18. ERROR in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/schema/EnumField.java (at line 37)
[ecj-lint] import org.apache.lucene.index.IndexOptions;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.index.IndexOptions is never used
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 19. ERROR in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/schema/FieldType.java (at line 38)
[ecj-lint] import org.apache.lucene.index.IndexOptions;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.index.IndexOptions is never used
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 20. ERROR in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/schema/LatLonType.java (at line 23)
[ecj-lint] import org.apache.lucene.document.FieldType;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.document.FieldType is never used
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 21. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/schema/OpenExchangeRatesOrgProvider.java (at line 146)
[ecj-lint] ratesJsonStream = resourceLoader.openResource(ratesFileLocation);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'ratesJsonStream' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 22. ERROR in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/schema/PointType.java (at line 24)
[ecj-lint] import org.apache.lucene.document.FieldType;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.document.FieldType is never used
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 23. ERROR in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/schema/TrieField.java (at line 32)
[ecj-lint] import org.apache.lucene.index.IndexOptions;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.index.IndexOptions is never used
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 24. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/CollapsingQParserPlugin.java (at line 1277)
[ecj-lint] UninvertingReader uninvertingReader = new UninvertingReader(new ReaderWrapper(searcher.getSlowAtomicReader(), collapseField), mapping);
[ecj-lint] ^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'uninvertingReader' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 25. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 145)
[ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len);
[ecj-lint] ^
[ecj-lint] Resource leak: 'h' is never closed
[ecj-lint] ----------
[ecj-lint] 26. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 154)
[ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len);
[ecj-lint] ^
[ecj-lint] Resource leak: 'h' is never closed
[ecj-lint] ----------
[ecj-lint] 27. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/DocSlice.java (at line 163)
[ecj-lint] HashDocSet h = new HashDocSet(docs,offset,len);
[ecj-lint] ^
[ecj-lint] Resource leak: 'h' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 28. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/function/OrdFieldSource.java (at line 78)
[ecj-lint] SolrIndexSearcher is = (SolrIndexSearcher) o;
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 29. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/search/function/ReverseOrdFieldSource.java (at line 78)
[ecj-lint] SolrIndexSearcher is = (SolrIndexSearcher) o;
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 30. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/servlet/SolrDispatchFilter.java (at line 229)
[ecj-lint] throw new SolrException(ErrorCode.SERVER_ERROR, "Error occurred while loading solr.xml from zookeeper", e);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'loader' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 31. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/update/CdcrTransactionLog.java (at line 98)
[ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos);
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 32. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 226)
[ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos);
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] 33. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/update/TransactionLog.java (at line 226)
[ecj-lint] ChannelFastInputStream is = new ChannelFastInputStream(channel, pos);
[ecj-lint] ^^
[ecj-lint] Resource leak: 'is' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 34. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/util/SimplePostTool.java (at line 640)
[ecj-lint] BAOS bos = new BAOS();
[ecj-lint] ^^^
[ecj-lint] Resource leak: 'bos' is never closed
[ecj-lint] ----------
[ecj-lint] 35. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/util/SimplePostTool.java (at line 640)
[ecj-lint] BAOS bos = new BAOS();
[ecj-lint] ^^^
[ecj-lint] Resource leak: 'bos' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 36. WARNING in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/java/org/apache/solr/util/SolrLogLayout.java (at line 149)
[ecj-lint] SolrQueryRequest req = requestInfo == null ? null : requestInfo.getReq();
[ecj-lint] ^^^
[ecj-lint] Resource leak: 'req' is never closed
[ecj-lint] ----------
[ecj-lint] 36 problems (5 errors, 31 warnings)
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:765: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/build.xml:101: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build.xml:671: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:1992: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/lucene/common-build.xml:2031: Compile failed; see the compiler error output for details.
Total time: 107 minutes 52 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any