Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id 1836A200BEB for ; Wed, 28 Dec 2016 21:58:39 +0100 (CET) Received: by cust-asf.ponee.io (Postfix) id 13C31160B2E; Wed, 28 Dec 2016 20:58:39 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id 91AA6160B2A for ; Wed, 28 Dec 2016 21:58:36 +0100 (CET) Received: (qmail 8706 invoked by uid 500); 28 Dec 2016 20:58:35 -0000 Mailing-List: contact dev-help@lucene.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@lucene.apache.org Delivered-To: mailing list dev@lucene.apache.org Received: (qmail 8696 invoked by uid 99); 28 Dec 2016 20:58:35 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd4-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 28 Dec 2016 20:58:35 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd4-us-west.apache.org (ASF Mail Server at spamd4-us-west.apache.org) with ESMTP id AADE7C0922 for ; Wed, 28 Dec 2016 20:58:34 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd4-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: 2 X-Spam-Level: ** X-Spam-Status: No, score=2 tagged_above=-999 required=6.31 tests=[KAM_BADIPHTTP=2, SPF_PASS=-0.001, WEIRD_PORT=0.001] autolearn=disabled Received: from mx1-lw-us.apache.org ([10.40.0.8]) by localhost (spamd4-us-west.apache.org [10.40.0.11]) (amavisd-new, port 10024) with ESMTP id XtwAfTt2vRUH for ; Wed, 28 Dec 2016 20:58:17 +0000 (UTC) Received: from serv1.sd-datasolutions.de (serv1.sd-datasolutions.de [188.138.57.78]) by mx1-lw-us.apache.org (ASF Mail Server at mx1-lw-us.apache.org) with ESMTPS id DE1955F24B for ; Wed, 28 Dec 2016 20:58:16 +0000 (UTC) Received: from serv1 (localhost.localdomain [127.0.0.1]) by serv1.sd-datasolutions.de (Postfix) with ESMTP id C8F394C1A99 for ; Wed, 28 Dec 2016 20:58:15 +0000 (UTC) Date: Wed, 28 Dec 2016 20:56:43 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1773326357.353.1482958695824.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-6.x-Linux (32bit/jdk1.8.0_112) - Build # 2534 - Unstable! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_352_1176838328.1482958695764" X-Jenkins-Job: Lucene-Solr-6.x-Linux X-Jenkins-Result: UNSTABLE archived-at: Wed, 28 Dec 2016 20:58:39 -0000 ------=_Part_352_1176838328.1482958695764 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/2534/ Java: 32bit/jdk1.8.0_112 -server -XX:+UseParallelGC 2 tests failed. FAILED: org.apache.solr.cloud.ShardSplitTest.testSplitAfterFailedSplit Error Message: expected:<1> but was:<2> Stack Trace: java.lang.AssertionError: expected:<1> but was:<2> =09at __randomizedtesting.SeedInfo.seed([12DC17AF670681F2:EB9184005B73CC78]= :0) =09at org.junit.Assert.fail(Assert.java:93) =09at org.junit.Assert.failNotEquals(Assert.java:647) =09at org.junit.Assert.assertEquals(Assert.java:128) =09at org.junit.Assert.assertEquals(Assert.java:472) =09at org.junit.Assert.assertEquals(Assert.java:456) =09at org.apache.solr.cloud.ShardSplitTest.testSplitAfterFailedSplit(ShardS= plitTest.java:280) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1713) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:907) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:957) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:967) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:367) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:811) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:462) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:916) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:802) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:852) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:863) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:367) =09at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.metrics.reporters.SolrGangliaReporterTest.testRepo= rter Error Message: Stack Trace: java.util.ConcurrentModificationException =09at __randomizedtesting.SeedInfo.seed([12DC17AF670681F2:4D383A980C0A12B7]= :0) =09at java.util.ArrayList$Itr.checkForComodification(ArrayList.java:901) =09at java.util.ArrayList$Itr.next(ArrayList.java:851) =09at org.apache.solr.metrics.reporters.SolrGangliaReporterTest.testReporte= r(SolrGangliaReporterTest.java:76) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1713) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:907) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(Random= izedRunner.java:943) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(Rando= mizedRunner.java:957) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:49) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:367) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:811) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:462) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:916) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:802) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:852) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:863) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:45) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:41) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRule= AssertionsRequired.java:53) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:47) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:64) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:54) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:367) =09at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11582 lines...] [junit4] Suite: org.apache.solr.metrics.reporters.SolrGangliaReporterTes= t [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-= Linux/solr/build/solr-core/test/J0/temp/solr.metrics.reporters.SolrGangliaR= eporterTest_12DC17AF670681F2-001/init-core-data-001 [junit4] 2> 756361 INFO (SUITE-SolrGangliaReporterTest-seed#[12DC17AF= 670681F2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and cl= ientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=3D, ssl=3DNa= N, value=3DNaN, clientAuth=3DNaN) [junit4] 2> 756362 INFO (TEST-SolrGangliaReporterTest.testReporter-se= ed#[12DC17AF670681F2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReporter [junit4] 2> 756596 INFO (TEST-SolrGangliaReporterTest.testReporter-se= ed#[12DC17AF670681F2]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateSha= rdHandler HTTP client with params: socketTimeout=3D600000&connTimeout=3D600= 00&retry=3Dtrue [junit4] 2> 756609 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from= paths: [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-f= iles/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-6.x-Linux/so= lr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 756629 WARN (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.c.Config Beginning with Solr 5.5, is deprecated= , use instead. [junit4] 2> 756630 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0 [junit4] 2> 757076 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 757136 WARN (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema [collection1] default search field in schema is= text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 757138 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 757144 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configu= ration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/= core/src/test-files/solr/collection1 [junit4] 2> 757144 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/je= nkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collect= ion1], dataDir=3D[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/= solr-core/test/J0/temp/solr.metrics.reporters.SolrGangliaReporterTest_12DC1= 7AF670681F2-001/init-core-data-001/] [junit4] 2> 757144 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbea= ns to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@884723 [junit4] 2> 757145 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3D45,= maxMergeAtOnceExplicit=3D13, maxMergedSegmentMB=3D55.5166015625, floorSegm= entMB=3D0.4443359375, forceMergeDeletesPctAllowed=3D15.332240744164856, seg= mentsPerTier=3D33.0, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio= =3D0.7750221932087835 [junit4] 2> 757157 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.so= lr.update.UpdateLog [junit4] 2> 757157 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLev= el=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets= =3D65536 [junit4] 2> 757158 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 757158 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 757158 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apac= he.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSi= ze=3D1677721, mergeFactor=3D15, maxMergeSize=3D2147483648, maxMergeSizeForF= orcedMerge=3D9223372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDo= cs=3D2147483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0= .7281319491530781] [junit4] 2> 757158 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3cff91[collection1] mai= n] [junit4] 2> 757158 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 757183 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.s.DirectSolrSpellChecker init: {name=3Ddirect,classname=3DDir= ectSolrSpellChecker,field=3Dlowerfilt,minQueryLength=3D3} [junit4] 2> 757254 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 757254 INFO (searcherExecutor-3150-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: default [junit4] 2> 757255 INFO (searcherExecutor-3150-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: direct [junit4] 2> 757255 INFO (searcherExecutor-3150-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: wordbreak [junit4] 2> 757255 INFO (searcherExecutor-3150-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: multipleFields [junit4] 2> 757255 INFO (searcherExecutor-3150-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: jarowinkler [junit4] 2> 757255 INFO (searcherExecutor-3150-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: external [junit4] 2> 757255 INFO (coreLoadExecutor-3149-thread-1) [ x:colle= ction1] o.a.s.u.UpdateLog Could not find max version in index or recent upd= ates, using new clock 1554993020785393664 [junit4] 2> 757255 INFO (searcherExecutor-3150-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: freq [junit4] 2> 757255 INFO (searcherExecutor-3150-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: fqcn [junit4] 2> 757255 INFO (searcherExecutor-3150-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spel= l index for spellchecker: perDict [junit4] 2> 757256 INFO (searcherExecutor-3150-thread-1-processing-x:= collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered = new searcher Searcher@3cff91[collection1] main{ExitableDirectoryReader(Unin= vertingDirectoryReader())} [junit4] 2> 762261 INFO (TEST-SolrGangliaReporterTest.testReporter-se= ed#[12DC17AF670681F2]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReporter [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DSolrGangliaRe= porterTest -Dtests.method=3DtestReporter -Dtests.seed=3D12DC17AF670681F2 -D= tests.multiplier=3D3 -Dtests.slow=3Dtrue -Dtests.locale=3Dsk-SK -Dtests.tim= ezone=3DPacific/Honolulu -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUS-= ASCII [junit4] ERROR 5.90s J0 | SolrGangliaReporterTest.testReporter <<< [junit4] > Throwable #1: java.util.ConcurrentModificationException [junit4] > =09at __randomizedtesting.SeedInfo.seed([12DC17AF670681F2:= 4D383A980C0A12B7]:0) [junit4] > =09at java.util.ArrayList$Itr.checkForComodification(Array= List.java:901) [junit4] > =09at java.util.ArrayList$Itr.next(ArrayList.java:851) [junit4] > =09at org.apache.solr.metrics.reporters.SolrGangliaReporte= rTest.testReporter(SolrGangliaReporterTest.java:76) [junit4] > =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 762264 INFO (SUITE-SolrGangliaReporterTest-seed#[12DC17AF= 670681F2]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 762264 INFO (SUITE-SolrGangliaReporterTest-seed#[12DC17AF= 670681F2]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer = instance=3D6801897 [junit4] 2> 762265 INFO (coreCloseExecutor-3155-thread-1) [ x:coll= ection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.c= ore.SolrCore@1c60fb3 [junit4] 2> 762275 INFO (coreCloseExecutor-3155-thread-1) [ x:coll= ection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.= collection1 [junit4] 2> 762275 INFO (SUITE-SolrGangliaReporterTest-seed#[12DC17AF= 670681F2]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters= for: solr.node [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/wo= rkspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.metric= s.reporters.SolrGangliaReporterTest_12DC17AF670681F2-001 [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene62): {}, do= cValues:{}, maxPointsInLeafNode=3D2029, maxMBSortInHeap=3D7.223022338520974= , sim=3DRandomSimilarity(queryNorm=3Dfalse,coord=3Dno): {}, locale=3Dsk-SK,= timezone=3DPacific/Honolulu [junit4] 2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 1.8.0= _112 (32-bit)/cpus=3D12,threads=3D1,free=3D238796824,total=3D327942144 [junit4] 2> NOTE: All tests run in this JVM: [ManagedSchemaRoundRobinC= loudTest, TestReplicationHandler, AsyncCallRequestStatusResponseTest, TestR= angeQuery, TestSolrCloudWithSecureImpersonation, RollingRestartTest, TestSt= andardQParsers, TestFieldCacheWithThreads, SolrSlf4jReporterTest, TestDocSe= t, TestSlowCompositeReaderWrapper, TestComponentsName, HdfsDirectoryTest, T= estCSVLoader, SolrRequestParserTest, HdfsCollectionsAPIDistributedZkTest, S= impleCollectionCreateDeleteTest, CoreMergeIndexesAdminHandlerTest, TestConf= igSetImmutable, DateRangeFieldTest, TestFoldingMultitermQuery, TestCodecSup= port, TestSortByMinMaxFunction, TestXmlQParser, TestRandomFlRTGCloud, TestD= ownShardTolerantSearch, TestCryptoKeys, OutputWriterTest, TestSha256Authent= icationProvider, TestQuerySenderNoQuery, TestSQLHandler, DirectoryFactoryTe= st, TestStressLucene, OverseerRolesTest, TestHighlightDedupGrouping, TestFa= stLRUCache, CacheHeaderTest, DisMaxRequestHandlerTest, TestBadConfig, TestU= niqueKeyFieldResource, TestCloudRecovery, DistribDocExpirationUpdateProcess= orTest, XsltUpdateRequestHandlerTest, TestSmileRequest, DateFieldTest, Test= SolrCloudWithHadoopAuthPlugin, TestCustomDocTransformer, ScriptEngineTest, = RankQueryTest, TestPerFieldSimilarityWithDefaultOverride, PathHierarchyToke= nizerFactoryTest, TestLRUStatsCache, TestSolrConfigHandler, TestIBSimilarit= yFactory, TestSizeLimitedDistributedMap, ChaosMonkeyNothingIsSafeTest, Test= GraphTermsQParserPlugin, PeerSyncWithIndexFingerprintCachingTest, Distribut= edFacetPivotSmallTest, ExternalFileFieldSortTest, AtomicUpdatesTest, TestSo= lrConfigHandlerConcurrent, PeerSyncReplicationTest, RuleEngineTest, Collect= ionTooManyReplicasTest, TestSolrCoreProperties, TestLocalFSCloudBackupResto= re, UtilsToolTest, HighlighterConfigTest, TestBlobHandler, SolrGangliaRepor= terTest] [junit4] Completed [274/675 (1!)] on J0 in 5.93s, 1 test, 1 error <<< FA= ILURES! [...truncated 1000 lines...] [junit4] Suite: org.apache.solr.cloud.ShardSplitTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-= Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF6= 70681F2-001/init-core-data-001 [junit4] 2> 1373933 INFO (SUITE-ShardSplitTest-seed#[12DC17AF670681F2= ]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth= (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=3Dhttps://= issues.apache.org/jira/browse/SOLR-5776) [junit4] 2> 1373933 INFO (SUITE-ShardSplitTest-seed#[12DC17AF670681F2= ]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext sy= stem property: / [junit4] 2> 1373934 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVE= R [junit4] 2> 1373935 INFO (Thread-2689) [ ] o.a.s.c.ZkTestServer cl= ient port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1373935 INFO (Thread-2689) [ ] o.a.s.c.ZkTestServer St= arting server [junit4] 2> 1374035 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.ZkTestServer start zk server on por= t:44173 [junit4] 2> 1374040 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1= /conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1374040 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1= /conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 1374041 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1= /conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig= .snippet.randomindexconfig.xml [junit4] 2> 1374042 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1= /conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1374042 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1= /conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1374043 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1= /conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1374043 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1= /conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1374044 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1= /conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1374044 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1= /conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent= .txt [junit4] 2> 1374045 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1= /conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1374045 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkin= s/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1= /conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1374110 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.SolrTestCaseJ4 Writing core.propertie= s file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/control-001/c= ores/collection1 [junit4] 2> 1374111 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1374112 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.Se= rvletContextHandler@1a0fc95{/,null,AVAILABLE} [junit4] 2> 1374112 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.AbstractConnector Started ServerCon= nector@4793f{HTTP/1.1,[http/1.1]}{127.0.0.1:38715} [junit4] 2> 1374113 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.Server Started @1375824ms [junit4] 2> 1374113 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty propertie= s: {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/buil= d/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/tem= pDir-001/control/data, hostContext=3D/, hostPort=3D38715, coreRootDirectory= =3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/= J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/control-001/cores} [junit4] 2> 1374113 ERROR (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Op= tion solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1374113 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ = Welcome to Apache Solr? version 6.4.0 [junit4] 2> 1374113 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ = Starting in cloud mode on port null [junit4] 2> 1374113 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|= Install dir: null [junit4] 2> 1374113 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| = Start time: 2016-12-28T20:38:43.021Z [junit4] 2> 1374115 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml= from SolrHome (not found in ZooKeeper) [junit4] 2> 1374115 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.SolrXmlConfig Loading container con= figuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/so= lr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/control= -001/solr.xml [junit4] 2> 1374121 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateS= hardHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D4= 5000&retry=3Dtrue [junit4] 2> 1374121 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.= 0.0.1:44173/solr [junit4] 2> 1374131 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:38715_ ] o.a.s.c.OverseerElectionC= ontext I am going to be the leader 127.0.0.1:38715_ [junit4] 2> 1374131 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:38715_ ] o.a.s.c.Overseer Overseer= (id=3D97187104217038852-127.0.0.1:38715_-n_0000000000) starting [junit4] 2> 1374136 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:38715_ ] o.a.s.c.ZkController Regi= ster node as live in ZooKeeper:/live_nodes/127.0.0.1:38715_ [junit4] 2> 1374137 INFO (zkCallback-2103-thread-1-processing-n:127.0= .0.1:38715_) [n:127.0.0.1:38715_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1374235 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:38715_ ] o.a.s.c.CorePropertiesLoc= ator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Sol= r-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12D= C17AF670681F2-001/control-001/cores [junit4] 2> 1374235 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:38715_ ] o.a.s.c.CorePropertiesLoc= ator Cores are: [collection1] [junit4] 2> 1374237 INFO (OverseerStateUpdate-97187104217038852-127.0= .0.1:38715_-n_0000000000) [n:127.0.0.1:38715_ ] o.a.s.c.o.ReplicaMutator= Assigning new node to shard shard=3Dshard1 [junit4] 2> 1375245 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0 [junit4] 2> 1375254 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 1375306 WARN (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.s.IndexSchema [collection1] default search field in schema is text.= WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 1375307 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 1375315 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration= from collection control_collection [junit4] 2> 1375315 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/= workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.clou= d.ShardSplitTest_12DC17AF670681F2-001/control-001/cores/collection1], dataD= ir=3D[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/te= st/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/control-001/cores= /collection1/data/] [junit4] 2> 1375315 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to = JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@8140f6 [junit4] 2> 1375317 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.luc= ene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=3D1000, merge= Factor=3D10, maxMergeSize=3D9223372036854775807, maxMergeSizeForForcedMerge= =3D9223372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D21474= 83647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.706696977= 0362581] [junit4] 2> 1375373 WARN (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =3D re= questHandler,name =3D /dump,class =3D DumpRequestHandler,attributes =3D {in= itParams=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {defaults= =3D{a=3DA,b=3DB}}} [junit4] 2> 1375379 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.upd= ate.UpdateLog [junit4] 2> 1375379 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DF= LUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1375380 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1375380 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1375380 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.luc= ene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=3D1000, merge= Factor=3D17, maxMergeSize=3D9223372036854775807, maxMergeSizeForForcedMerge= =3D9223372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D21474= 83647, maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.0] [junit4] 2> 1375380 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1909597[collection1] main] [junit4] 2> 1375381 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBa= se: /configs/conf1 [junit4] 2> 1375381 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json usi= ng ZooKeeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1375381 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1375382 INFO (searcherExecutor-6426-thread-1-processing-n= :127.0.0.1:38715_ x:collection1 c:control_collection) [n:127.0.0.1:38715_ c= :control_collection x:collection1] o.a.s.c.SolrCore [collection1] Registe= red new searcher Searcher@1909597[collection1] main{ExitableDirectoryReader= (UninvertingDirectoryReader())} [junit4] 2> 1375382 INFO (coreLoadExecutor-6425-thread-1-processing-n= :127.0.0.1:38715_) [n:127.0.0.1:38715_ c:control_collection x:collection1= ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, = using new clock 1554993668989911040 [junit4] 2> 1375385 INFO (coreZkRegister-6418-thread-1-processing-n:1= 27.0.0.1:38715_ x:collection1 c:control_collection) [n:127.0.0.1:38715_ c:c= ontrol_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderE= lectionContext Enough replicas found to continue. [junit4] 2> 1375385 INFO (coreZkRegister-6418-thread-1-processing-n:1= 27.0.0.1:38715_ x:collection1 c:control_collection) [n:127.0.0.1:38715_ c:c= ontrol_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderE= lectionContext I may be the new leader - try and sync [junit4] 2> 1375386 INFO (coreZkRegister-6418-thread-1-processing-n:1= 27.0.0.1:38715_ x:collection1 c:control_collection) [n:127.0.0.1:38715_ c:c= ontrol_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy= Sync replicas to http://127.0.0.1:38715/collection1/ [junit4] 2> 1375386 INFO (coreZkRegister-6418-thread-1-processing-n:1= 27.0.0.1:38715_ x:collection1 c:control_collection) [n:127.0.0.1:38715_ c:c= ontrol_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy= Sync Success - now sync replicas to me [junit4] 2> 1375386 INFO (coreZkRegister-6418-thread-1-processing-n:1= 27.0.0.1:38715_ x:collection1 c:control_collection) [n:127.0.0.1:38715_ c:c= ontrol_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy= http://127.0.0.1:38715/collection1/ has no replicas [junit4] 2> 1375387 INFO (coreZkRegister-6418-thread-1-processing-n:1= 27.0.0.1:38715_ x:collection1 c:control_collection) [n:127.0.0.1:38715_ c:c= ontrol_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderE= lectionContext I am the new leader: http://127.0.0.1:38715/collection1/ sha= rd1 [junit4] 2> 1375394 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes = from ZooKeeper... (0) -> (1) [junit4] 2> 1375394 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cl= uster at 127.0.0.1:44173/solr ready [junit4] 2> 1375394 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire s= essions:false cause connection loss:false [junit4] 2> 1375460 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.SolrTestCaseJ4 Writing core.propertie= s file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-1-001/c= ores/collection1 [junit4] 2> 1375461 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractFullDistribZkTestBase creat= e jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/b= uild/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/= shard-1-001 [junit4] 2> 1375461 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1375462 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.Se= rvletContextHandler@1161ae1{/,null,AVAILABLE} [junit4] 2> 1375462 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.AbstractConnector Started ServerCon= nector@1bf8408{HTTP/1.1,[http/1.1]}{127.0.0.1:41332} [junit4] 2> 1375462 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.Server Started @1377174ms [junit4] 2> 1375462 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty propertie= s: {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/buil= d/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/tem= pDir-001/jetty1, solrconfig=3Dsolrconfig.xml, hostContext=3D/, hostPort=3D4= 1332, coreRootDirectory=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/sol= r/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-0= 01/shard-1-001/cores} [junit4] 2> 1375463 ERROR (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Op= tion solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1375463 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ = Welcome to Apache Solr? version 6.4.0 [junit4] 2> 1375463 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ = Starting in cloud mode on port null [junit4] 2> 1375463 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|= Install dir: null [junit4] 2> 1375463 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| = Start time: 2016-12-28T20:38:44.371Z [junit4] 2> 1375465 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml= from SolrHome (not found in ZooKeeper) [junit4] 2> 1375465 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.SolrXmlConfig Loading container con= figuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/so= lr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-1= -001/solr.xml [junit4] 2> 1375470 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateS= hardHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D4= 5000&retry=3Dtrue [junit4] 2> 1375470 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.= 0.0.1:44173/solr [junit4] 2> 1375475 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:41332_ ] o.a.s.c.c.ZkStateReader U= pdated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1375477 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:41332_ ] o.a.s.c.ZkController Regi= ster node as live in ZooKeeper:/live_nodes/127.0.0.1:41332_ [junit4] 2> 1375478 INFO (zkCallback-2103-thread-1-processing-n:127.0= .0.1:38715_) [n:127.0.0.1:38715_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1375478 INFO (zkCallback-2107-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1375478 INFO (zkCallback-2112-thread-1-processing-n:127.0= .0.1:41332_) [n:127.0.0.1:41332_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1375562 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:41332_ ] o.a.s.c.CorePropertiesLoc= ator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Sol= r-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12D= C17AF670681F2-001/shard-1-001/cores [junit4] 2> 1375562 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:41332_ ] o.a.s.c.CorePropertiesLoc= ator Cores are: [collection1] [junit4] 2> 1375563 INFO (OverseerStateUpdate-97187104217038852-127.0= .0.1:38715_-n_0000000000) [n:127.0.0.1:38715_ ] o.a.s.c.o.ReplicaMutator= Assigning new node to shard shard=3Dshard2 [junit4] 2> 1375688 INFO (coreZkRegister-6418-thread-1-processing-n:1= 27.0.0.1:38715_ x:collection1 c:control_collection) [n:127.0.0.1:38715_ c:c= ontrol_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController= I am the leader, no recovery necessary [junit4] 2> 1376571 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .c.SolrConfig Using Lucene MatchVersion: 6.4.0 [junit4] 2> 1376580 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 1376631 WARN (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .s.IndexSchema [collection1] default search field in schema is text. WARNIN= G: Deprecated, please use 'df' on request instead. [junit4] 2> 1376632 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 1376639 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .c.CoreContainer Creating SolrCore 'collection1' using configuration from c= ollection collection1 [junit4] 2> 1376640 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspa= ce/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Shard= SplitTest_12DC17AF670681F2-001/shard-1-001/cores/collection1], dataDir=3D[/= home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/t= emp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-1-001/cores/collec= tion1/data/] [junit4] 2> 1376640 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Ser= ver: com.sun.jmx.mbeanserver.JmxMBeanServer@8140f6 [junit4] 2> 1376641 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.ind= ex.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=3D1000, mergeFactor= =3D10, maxMergeSize=3D9223372036854775807, maxMergeSizeForForcedMerge=3D922= 3372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147483647,= maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.706696977036258= 1] [junit4] 2> 1376664 WARN (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .c.RequestHandlers INVALID paramSet a in requestHandler {type =3D requestHa= ndler,name =3D /dump,class =3D DumpRequestHandler,attributes =3D {initParam= s=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {defaults=3D{a=3D= A,b=3DB}}} [junit4] 2> 1376671 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Upd= ateLog [junit4] 2> 1376671 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH nu= mRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1376672 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1376672 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1376672 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.ind= ex.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=3D1000, mergeFactor= =3D17, maxMergeSize=3D9223372036854775807, maxMergeSizeForForcedMerge=3D922= 3372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147483647,= maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.0] [junit4] 2> 1376673 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .s.SolrIndexSearcher Opening [Searcher@1de5a8f[collection1] main] [junit4] 2> 1376673 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /co= nfigs/conf1 [junit4] 2> 1376673 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooK= eeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1376674 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1376674 INFO (searcherExecutor-6437-thread-1-processing-n= :127.0.0.1:41332_ x:collection1 c:collection1) [n:127.0.0.1:41332_ c:collec= tion1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new search= er Searcher@1de5a8f[collection1] main{ExitableDirectoryReader(UninvertingDi= rectoryReader())} [junit4] 2> 1376674 INFO (coreLoadExecutor-6436-thread-1-processing-n= :127.0.0.1:41332_) [n:127.0.0.1:41332_ c:collection1 x:collection1] o.a.s= .u.UpdateLog Could not find max version in index or recent updates, using n= ew clock 1554993670344671232 [junit4] 2> 1376678 INFO (coreZkRegister-6431-thread-1-processing-n:1= 27.0.0.1:41332_ x:collection1 c:collection1) [n:127.0.0.1:41332_ c:collecti= on1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext= Enough replicas found to continue. [junit4] 2> 1376678 INFO (coreZkRegister-6431-thread-1-processing-n:1= 27.0.0.1:41332_ x:collection1 c:collection1) [n:127.0.0.1:41332_ c:collecti= on1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext= I may be the new leader - try and sync [junit4] 2> 1376678 INFO (coreZkRegister-6431-thread-1-processing-n:1= 27.0.0.1:41332_ x:collection1 c:collection1) [n:127.0.0.1:41332_ c:collecti= on1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas= to http://127.0.0.1:41332/collection1/ [junit4] 2> 1376678 INFO (coreZkRegister-6431-thread-1-processing-n:1= 27.0.0.1:41332_ x:collection1 c:collection1) [n:127.0.0.1:41332_ c:collecti= on1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success = - now sync replicas to me [junit4] 2> 1376678 INFO (coreZkRegister-6431-thread-1-processing-n:1= 27.0.0.1:41332_ x:collection1 c:collection1) [n:127.0.0.1:41332_ c:collecti= on1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.= 0.1:41332/collection1/ has no replicas [junit4] 2> 1376680 INFO (coreZkRegister-6431-thread-1-processing-n:1= 27.0.0.1:41332_ x:collection1 c:collection1) [n:127.0.0.1:41332_ c:collecti= on1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext= I am the new leader: http://127.0.0.1:41332/collection1/ shard2 [junit4] 2> 1376830 INFO (coreZkRegister-6431-thread-1-processing-n:1= 27.0.0.1:41332_ x:collection1 c:collection1) [n:127.0.0.1:41332_ c:collecti= on1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the lead= er, no recovery necessary [junit4] 2> 1376846 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.SolrTestCaseJ4 Writing core.propertie= s file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-2-001/c= ores/collection1 [junit4] 2> 1376846 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractFullDistribZkTestBase creat= e jetty 2 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/b= uild/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/= shard-2-001 [junit4] 2> 1376847 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1376848 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.Se= rvletContextHandler@1de01dc{/,null,AVAILABLE} [junit4] 2> 1376848 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.AbstractConnector Started ServerCon= nector@1a5f1fc{HTTP/1.1,[http/1.1]}{127.0.0.1:44148} [junit4] 2> 1376848 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.Server Started @1378560ms [junit4] 2> 1376848 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty propertie= s: {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/buil= d/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/tem= pDir-001/jetty2, solrconfig=3Dsolrconfig.xml, hostContext=3D/, hostPort=3D4= 4148, coreRootDirectory=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/sol= r/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-0= 01/shard-2-001/cores} [junit4] 2> 1376849 ERROR (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Op= tion solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1376849 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ = Welcome to Apache Solr? version 6.4.0 [junit4] 2> 1376849 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ = Starting in cloud mode on port null [junit4] 2> 1376849 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|= Install dir: null [junit4] 2> 1376849 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| = Start time: 2016-12-28T20:38:45.757Z [junit4] 2> 1376851 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml= from SolrHome (not found in ZooKeeper) [junit4] 2> 1376851 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.SolrXmlConfig Loading container con= figuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/so= lr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-2= -001/solr.xml [junit4] 2> 1376857 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateS= hardHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D4= 5000&retry=3Dtrue [junit4] 2> 1376857 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.= 0.0.1:44173/solr [junit4] 2> 1376862 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:44148_ ] o.a.s.c.c.ZkStateReader U= pdated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1376864 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:44148_ ] o.a.s.c.ZkController Regi= ster node as live in ZooKeeper:/live_nodes/127.0.0.1:44148_ [junit4] 2> 1376865 INFO (zkCallback-2103-thread-1-processing-n:127.0= .0.1:38715_) [n:127.0.0.1:38715_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1376865 INFO (zkCallback-2107-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1376865 INFO (zkCallback-2112-thread-1-processing-n:127.0= .0.1:41332_) [n:127.0.0.1:41332_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1376865 INFO (zkCallback-2118-thread-1-processing-n:127.0= .0.1:44148_) [n:127.0.0.1:44148_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1376946 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:44148_ ] o.a.s.c.CorePropertiesLoc= ator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Sol= r-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12D= C17AF670681F2-001/shard-2-001/cores [junit4] 2> 1376946 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:44148_ ] o.a.s.c.CorePropertiesLoc= ator Cores are: [collection1] [junit4] 2> 1376947 INFO (OverseerStateUpdate-97187104217038852-127.0= .0.1:38715_-n_0000000000) [n:127.0.0.1:38715_ ] o.a.s.c.o.ReplicaMutator= Assigning new node to shard shard=3Dshard1 [junit4] 2> 1377954 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .c.SolrConfig Using Lucene MatchVersion: 6.4.0 [junit4] 2> 1377963 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 1378006 WARN (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .s.IndexSchema [collection1] default search field in schema is text. WARNIN= G: Deprecated, please use 'df' on request instead. [junit4] 2> 1378007 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 1378015 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .c.CoreContainer Creating SolrCore 'collection1' using configuration from c= ollection collection1 [junit4] 2> 1378015 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspa= ce/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Shard= SplitTest_12DC17AF670681F2-001/shard-2-001/cores/collection1], dataDir=3D[/= home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/t= emp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-2-001/cores/collec= tion1/data/] [junit4] 2> 1378015 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Ser= ver: com.sun.jmx.mbeanserver.JmxMBeanServer@8140f6 [junit4] 2> 1378017 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.ind= ex.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=3D1000, mergeFactor= =3D10, maxMergeSize=3D9223372036854775807, maxMergeSizeForForcedMerge=3D922= 3372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147483647,= maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.706696977036258= 1] [junit4] 2> 1378046 WARN (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .c.RequestHandlers INVALID paramSet a in requestHandler {type =3D requestHa= ndler,name =3D /dump,class =3D DumpRequestHandler,attributes =3D {initParam= s=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {defaults=3D{a=3D= A,b=3DB}}} [junit4] 2> 1378054 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Upd= ateLog [junit4] 2> 1378054 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH nu= mRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1378055 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1378055 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1378056 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.ind= ex.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=3D1000, mergeFactor= =3D17, maxMergeSize=3D9223372036854775807, maxMergeSizeForForcedMerge=3D922= 3372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147483647,= maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.0] [junit4] 2> 1378056 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .s.SolrIndexSearcher Opening [Searcher@1724885[collection1] main] [junit4] 2> 1378056 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /co= nfigs/conf1 [junit4] 2> 1378057 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooK= eeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1378057 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1378057 INFO (searcherExecutor-6448-thread-1-processing-n= :127.0.0.1:44148_ x:collection1 c:collection1) [n:127.0.0.1:44148_ c:collec= tion1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new search= er Searcher@1724885[collection1] main{ExitableDirectoryReader(UninvertingDi= rectoryReader())} [junit4] 2> 1378057 INFO (coreLoadExecutor-6447-thread-1-processing-n= :127.0.0.1:44148_) [n:127.0.0.1:44148_ c:collection1 x:collection1] o.a.s= .u.UpdateLog Could not find max version in index or recent updates, using n= ew clock 1554993671794851840 [junit4] 2> 1378061 INFO (coreZkRegister-6442-thread-1-processing-n:1= 27.0.0.1:44148_ x:collection1 c:collection1) [n:127.0.0.1:44148_ c:collecti= on1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext= Enough replicas found to continue. [junit4] 2> 1378061 INFO (coreZkRegister-6442-thread-1-processing-n:1= 27.0.0.1:44148_ x:collection1 c:collection1) [n:127.0.0.1:44148_ c:collecti= on1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext= I may be the new leader - try and sync [junit4] 2> 1378061 INFO (coreZkRegister-6442-thread-1-processing-n:1= 27.0.0.1:44148_ x:collection1 c:collection1) [n:127.0.0.1:44148_ c:collecti= on1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync replicas= to http://127.0.0.1:44148/collection1/ [junit4] 2> 1378061 INFO (coreZkRegister-6442-thread-1-processing-n:1= 27.0.0.1:44148_ x:collection1 c:collection1) [n:127.0.0.1:44148_ c:collecti= on1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy Sync Success = - now sync replicas to me [junit4] 2> 1378061 INFO (coreZkRegister-6442-thread-1-processing-n:1= 27.0.0.1:44148_ x:collection1 c:collection1) [n:127.0.0.1:44148_ c:collecti= on1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy http://127.0.= 0.1:44148/collection1/ has no replicas [junit4] 2> 1378062 INFO (coreZkRegister-6442-thread-1-processing-n:1= 27.0.0.1:44148_ x:collection1 c:collection1) [n:127.0.0.1:44148_ c:collecti= on1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext= I am the new leader: http://127.0.0.1:44148/collection1/ shard1 [junit4] 2> 1378173 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.SolrTestCaseJ4 Writing core.propertie= s file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-3-001/c= ores/collection1 [junit4] 2> 1378174 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractFullDistribZkTestBase creat= e jetty 3 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/b= uild/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/= shard-3-001 [junit4] 2> 1378174 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1378175 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.Se= rvletContextHandler@9dc33b{/,null,AVAILABLE} [junit4] 2> 1378176 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.AbstractConnector Started ServerCon= nector@1b3c5c9{HTTP/1.1,[http/1.1]}{127.0.0.1:44209} [junit4] 2> 1378176 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.Server Started @1379887ms [junit4] 2> 1378176 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty propertie= s: {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/buil= d/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/tem= pDir-001/jetty3, solrconfig=3Dsolrconfig.xml, hostContext=3D/, hostPort=3D4= 4209, coreRootDirectory=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/sol= r/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-0= 01/shard-3-001/cores} [junit4] 2> 1378176 ERROR (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Op= tion solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1378176 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ = Welcome to Apache Solr? version 6.4.0 [junit4] 2> 1378176 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ = Starting in cloud mode on port null [junit4] 2> 1378176 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|= Install dir: null [junit4] 2> 1378176 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| = Start time: 2016-12-28T20:38:47.084Z [junit4] 2> 1378178 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml= from SolrHome (not found in ZooKeeper) [junit4] 2> 1378178 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.SolrXmlConfig Loading container con= figuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/so= lr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-3= -001/solr.xml [junit4] 2> 1378182 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateS= hardHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D4= 5000&retry=3Dtrue [junit4] 2> 1378183 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.= 0.0.1:44173/solr [junit4] 2> 1378187 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:44209_ ] o.a.s.c.c.ZkStateReader U= pdated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1378189 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:44209_ ] o.a.s.c.ZkController Regi= ster node as live in ZooKeeper:/live_nodes/127.0.0.1:44209_ [junit4] 2> 1378190 INFO (zkCallback-2103-thread-1-processing-n:127.0= .0.1:38715_) [n:127.0.0.1:38715_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1378190 INFO (zkCallback-2107-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1378191 INFO (zkCallback-2124-thread-1-processing-n:127.0= .0.1:44209_) [n:127.0.0.1:44209_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1378191 INFO (zkCallback-2118-thread-1-processing-n:127.0= .0.1:44148_) [n:127.0.0.1:44148_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1378190 INFO (zkCallback-2112-thread-1-processing-n:127.0= .0.1:41332_) [n:127.0.0.1:41332_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (3) -> (4) [junit4] 2> 1378213 INFO (coreZkRegister-6442-thread-1-processing-n:1= 27.0.0.1:44148_ x:collection1 c:collection1) [n:127.0.0.1:44148_ c:collecti= on1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ZkController I am the lead= er, no recovery necessary [junit4] 2> 1378240 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:44209_ ] o.a.s.c.CorePropertiesLoc= ator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Sol= r-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12D= C17AF670681F2-001/shard-3-001/cores [junit4] 2> 1378240 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:44209_ ] o.a.s.c.CorePropertiesLoc= ator Cores are: [collection1] [junit4] 2> 1378241 INFO (OverseerStateUpdate-97187104217038852-127.0= .0.1:38715_-n_0000000000) [n:127.0.0.1:38715_ ] o.a.s.c.o.ReplicaMutator= Assigning new node to shard shard=3Dshard2 [junit4] 2> 1379248 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .c.SolrConfig Using Lucene MatchVersion: 6.4.0 [junit4] 2> 1379257 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 1379302 WARN (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .s.IndexSchema [collection1] default search field in schema is text. WARNIN= G: Deprecated, please use 'df' on request instead. [junit4] 2> 1379304 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 1379312 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .c.CoreContainer Creating SolrCore 'collection1' using configuration from c= ollection collection1 [junit4] 2> 1379312 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspa= ce/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Shard= SplitTest_12DC17AF670681F2-001/shard-3-001/cores/collection1], dataDir=3D[/= home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/t= emp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-3-001/cores/collec= tion1/data/] [junit4] 2> 1379312 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Ser= ver: com.sun.jmx.mbeanserver.JmxMBeanServer@8140f6 [junit4] 2> 1379313 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.ind= ex.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=3D1000, mergeFactor= =3D10, maxMergeSize=3D9223372036854775807, maxMergeSizeForForcedMerge=3D922= 3372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147483647,= maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.706696977036258= 1] [junit4] 2> 1379336 WARN (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .c.RequestHandlers INVALID paramSet a in requestHandler {type =3D requestHa= ndler,name =3D /dump,class =3D DumpRequestHandler,attributes =3D {initParam= s=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {defaults=3D{a=3D= A,b=3DB}}} [junit4] 2> 1379344 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Upd= ateLog [junit4] 2> 1379345 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH nu= mRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1379345 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1379345 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1379346 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.ind= ex.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=3D1000, mergeFactor= =3D17, maxMergeSize=3D9223372036854775807, maxMergeSizeForForcedMerge=3D922= 3372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147483647,= maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.0] [junit4] 2> 1379346 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .s.SolrIndexSearcher Opening [Searcher@17858cd[collection1] main] [junit4] 2> 1379346 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /co= nfigs/conf1 [junit4] 2> 1379347 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooK= eeperStorageIO:path=3D/configs/conf1 [junit4] 2> 1379347 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1379347 INFO (searcherExecutor-6459-thread-1-processing-n= :127.0.0.1:44209_ x:collection1 c:collection1) [n:127.0.0.1:44209_ c:collec= tion1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new search= er Searcher@17858cd[collection1] main{ExitableDirectoryReader(UninvertingDi= rectoryReader())} [junit4] 2> 1379347 INFO (coreLoadExecutor-6458-thread-1-processing-n= :127.0.0.1:44209_) [n:127.0.0.1:44209_ c:collection1 x:collection1] o.a.s= .u.UpdateLog Could not find max version in index or recent updates, using n= ew clock 1554993673147514880 [junit4] 2> 1379350 INFO (coreZkRegister-6453-thread-1-processing-n:1= 27.0.0.1:44209_ x:collection1 c:collection1) [n:127.0.0.1:44209_ c:collecti= on1 s:shard2 r:core_node3 x:collection1] o.a.s.c.ZkController Core needs to= recover:collection1 [junit4] 2> 1379350 INFO (updateExecutor-2121-thread-1-processing-n:1= 27.0.0.1:44209_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127.0= .0.1:44209_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.Defa= ultSolrCoreState Running recovery [junit4] 2> 1379351 INFO (recoveryExecutor-2122-thread-1-processing-n= :127.0.0.1:44209_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127= .0.0.1:44209_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.Re= coveryStrategy Starting recovery process. recoveringAfterStartup=3Dtrue [junit4] 2> 1379351 INFO (recoveryExecutor-2122-thread-1-processing-n= :127.0.0.1:44209_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127= .0.0.1:44209_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.Re= coveryStrategy ###### startupVersions=3D[[]] [junit4] 2> 1379351 INFO (recoveryExecutor-2122-thread-1-processing-n= :127.0.0.1:44209_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127= .0.0.1:44209_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.Re= coveryStrategy Begin buffering updates. core=3D[collection1] [junit4] 2> 1379351 INFO (recoveryExecutor-2122-thread-1-processing-n= :127.0.0.1:44209_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127= .0.0.1:44209_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.Up= dateLog Starting to buffer updates. FSUpdateLog{state=3DACTIVE, tlog=3Dnull= } [junit4] 2> 1379351 INFO (recoveryExecutor-2122-thread-1-processing-n= :127.0.0.1:44209_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127= .0.0.1:44209_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.Re= coveryStrategy Publishing state of core [collection1] as recovering, leader= is [http://127.0.0.1:41332/collection1/] and I am [http://127.0.0.1:44209/= collection1/] [junit4] 2> 1379353 INFO (recoveryExecutor-2122-thread-1-processing-n= :127.0.0.1:44209_ x:collection1 s:shard2 c:collection1 r:core_node3) [n:127= .0.0.1:44209_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.Re= coveryStrategy Sending prep recovery command to [http://127.0.0.1:41332]; [= WaitForState: action=3DPREPRECOVERY&core=3Dcollection1&nodeName=3D127.0.0.1= :44209_&coreNodeName=3Dcore_node3&state=3Drecovering&checkLive=3Dtrue&onlyI= fLeader=3Dtrue&onlyIfLeaderActive=3Dtrue] [junit4] 2> 1379354 INFO (qtp13156919-11464) [n:127.0.0.1:41332_ ]= o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node3, state= : recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true [junit4] 2> 1379354 INFO (qtp13156919-11464) [n:127.0.0.1:41332_ ]= o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1= (shard2 of collection1) have state: recovering [junit4] 2> 1379354 INFO (qtp13156919-11464) [n:127.0.0.1:41332_ ]= o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=3Dcollect= ion1, shard=3Dshard2, thisCore=3Dcollection1, leaderDoesNotNeedRecovery=3Df= alse, isLeader? true, live=3Dtrue, checkLive=3Dtrue, currentState=3Ddown, l= ocalState=3Dactive, nodeName=3D127.0.0.1:44209_, coreNodeName=3Dcore_node3,= onlyIfActiveCheckResult=3Dfalse, nodeProps: core_node3:{"core":"collection= 1","base_url":"http://127.0.0.1:44209","node_name":"127.0.0.1:44209_","stat= e":"down"} [junit4] 2> 1379455 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.SolrTestCaseJ4 Writing core.propertie= s file to /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-cor= e/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-4-001/c= ores/collection1 [junit4] 2> 1379455 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.AbstractFullDistribZkTestBase creat= e jetty 4 in directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/b= uild/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/= shard-4-001 [junit4] 2> 1379456 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 1379456 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.Se= rvletContextHandler@f37b92{/,null,AVAILABLE} [junit4] 2> 1379457 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.AbstractConnector Started ServerCon= nector@17dd99f{HTTP/1.1,[http/1.1]}{127.0.0.1:38741} [junit4] 2> 1379457 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.e.j.s.Server Started @1381168ms [junit4] 2> 1379457 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty propertie= s: {solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/buil= d/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/tem= pDir-001/jetty4, solrconfig=3Dsolrconfig.xml, hostContext=3D/, hostPort=3D3= 8741, coreRootDirectory=3D/home/jenkins/workspace/Lucene-Solr-6.x-Linux/sol= r/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace= /Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.ShardSp= litTest_12DC17AF670681F2-001/shard-4-001/cores} [junit4] 2> 1379457 ERROR (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Op= tion solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1379457 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ = Welcome to Apache Solr? version 6.4.0 [junit4] 2> 1379458 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ = Starting in cloud mode on port null [junit4] 2> 1379458 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|= Install dir: null [junit4] 2> 1379458 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| = Start time: 2016-12-28T20:38:48.366Z [junit4] 2> 1379460 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml= from SolrHome (not found in ZooKeeper) [junit4] 2> 1379460 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.SolrXmlConfig Loading container con= figuration from /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/so= lr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-4= -001/solr.xml [junit4] 2> 1379464 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateS= hardHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D4= 5000&retry=3Dtrue [junit4] 2> 1379465 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127.= 0.0.1:44173/solr [junit4] 2> 1379469 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:38741_ ] o.a.s.c.c.ZkStateReader U= pdated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 1379470 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:38741_ ] o.a.s.c.ZkController Regi= ster node as live in ZooKeeper:/live_nodes/127.0.0.1:38741_ [junit4] 2> 1379472 INFO (zkCallback-2131-thread-1-processing-n:127.0= .0.1:38741_) [n:127.0.0.1:38741_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1379472 INFO (zkCallback-2118-thread-1-processing-n:127.0= .0.1:44148_) [n:127.0.0.1:44148_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1379472 INFO (zkCallback-2112-thread-1-processing-n:127.0= .0.1:41332_) [n:127.0.0.1:41332_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1379472 INFO (zkCallback-2124-thread-1-processing-n:127.0= .0.1:44209_) [n:127.0.0.1:44209_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1379472 INFO (zkCallback-2103-thread-2-processing-n:127.0= .0.1:38715_) [n:127.0.0.1:38715_ ] o.a.s.c.c.ZkStateReader Updated live = nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1379472 INFO (zkCallback-2107-thread-1) [ ] o.a.s.c.c.= ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 1379525 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:38741_ ] o.a.s.c.CorePropertiesLoc= ator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Sol= r-6.x-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/je= nkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/sol= r.cloud.ShardSplitTest_12DC17AF670681F2-001/shard-4-001/cores [junit4] 2> 1379525 INFO (TEST-ShardSplitTest.testSplitShardWithRule-= seed#[12DC17AF670681F2]) [n:127.0.0.1:38741_ ] o.a.s.c.CorePropertiesLoc= ator Cores are: [collection1] [junit4] 2> 1379526 INFO (OverseerStateUpdate-97187104217038852-127.0= .0.1:38715_-n_0000000000) [n:127.0.0.1:38715_ ] o.a.s.c.o.ReplicaMutator= Assigning new node to shard shard=3Dshard1 [junit4] 2> 1380354 INFO (qtp13156919-11464) [n:127.0.0.1:41332_ ]= o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=3Dcollect= ion1, shard=3Dshard2, thisCore=3Dcollection1, leaderDoesNotNeedRecovery=3Df= alse, isLeader? true, live=3Dtrue, checkLive=3Dtrue, currentState=3Drecover= ing, localState=3Dactive, nodeName=3D127.0.0.1:44209_, coreNodeName=3Dcore_= node3, onlyIfActiveCheckResult=3Dfalse, nodeProps: core_node3:{"core":"coll= ection1","base_url":"http://127.0.0.1:44209","node_name":"127.0.0.1:44209_"= ,"state":"recovering"} [junit4] 2> 1380354 INFO (qtp13156919-11464) [n:127.0.0.1:41332_ ]= o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node3, state: recoverin= g, checkLive: true, onlyIfLeader: true for: 1 seconds. [junit4] 2> 1380354 INFO (qtp13156919-11464) [n:127.0.0.1:41332_ ]= o.a.s.s.HttpSolrCall [admin] webapp=3Dnull path=3D/admin/cores params=3D{n= odeName=3D127.0.0.1:44209_&onlyIfLeaderActive=3Dtrue&core=3Dcollection1&cor= eNodeName=3Dcore_node3&action=3DPREPRECOVERY&checkLive=3Dtrue&state=3Drecov= ering&onlyIfLeader=3Dtrue&wt=3Djavabin&version=3D2} status=3D0 QTime=3D1000 [junit4] 2> 1380533 INFO (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .c.SolrConfig Using Lucene MatchVersion: 6.4.0 [junit4] 2> 1380542 INFO (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 1380585 WARN (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .s.IndexSchema [collection1] default search field in schema is text. WARNIN= G: Deprecated, please use 'df' on request instead. [junit4] 2> 1380586 INFO (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 1380594 INFO (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .c.CoreContainer Creating SolrCore 'collection1' using configuration from c= ollection collection1 [junit4] 2> 1380595 INFO (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspa= ce/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.Shard= SplitTest_12DC17AF670681F2-001/shard-4-001/cores/collection1], dataDir=3D[/= home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/.= ./../../../../../../../../home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr= /build/solr-core/test/J2/temp/solr.cloud.ShardSplitTest_12DC17AF670681F2-00= 1/shard-4-001/cores/collection1/data/] [junit4] 2> 1380595 INFO (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Ser= ver: com.sun.jmx.mbeanserver.JmxMBeanServer@8140f6 [junit4] 2> 1380596 INFO (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.ind= ex.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=3D1000, mergeFactor= =3D10, maxMergeSize=3D9223372036854775807, maxMergeSizeForForcedMerge=3D922= 3372036854775807, calibrateSizeByDeletes=3Dtrue, maxMergeDocs=3D2147483647,= maxCFSSegmentSizeMB=3D8.796093022207999E12, noCFSRatio=3D0.706696977036258= 1] [junit4] 2> 1380629 WARN (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .c.RequestHandlers INVALID paramSet a in requestHandler {type =3D requestHa= ndler,name =3D /dump,class =3D DumpRequestHandler,attributes =3D {initParam= s=3Da, name=3D/dump, class=3DDumpRequestHandler},args =3D {defaults=3D{a=3D= A,b=3DB}}} [junit4] 2> 1380637 INFO (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.Upd= ateLog [junit4] 2> 1380637 INFO (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .u.UpdateLog Initializing UpdateLog: dataDir=3D defaultSyncLevel=3DFLUSH nu= mRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVersionBuckets=3D65536 [junit4] 2> 1380638 INFO (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1380638 INFO (coreLoadExecutor-6469-thread-1-processing-n= :127.0.0.1:38741_) [n:127.0.0.1:38741_ c:collection1 x:collection1] o.a.s= .u.CommitTracker Soft AutoCommit: disabled [junit [...truncated too long message...] 1F2]) [ ] o.a.s.c.ChaosMonkey monkey: stop shard! 39626 [junit4] 2> 1785360 INFO (TEST-ShardSplitTest.testSplitAfterFailedSpl= it-seed#[12DC17AF670681F2]) [ ] o.a.s.c.CoreContainer Shutting down Core= Container instance=3D154048 [junit4] 2> 1785367 INFO (TEST-ShardSplitTest.testSplitAfterFailedSpl= it-seed#[12DC17AF670681F2]) [ ] o.a.s.c.Overseer Overseer (id=3D97187129= 295306769-127.0.0.1:39626_-n_0000000004) closing [junit4] 2> 1785367 INFO (OverseerStateUpdate-97187129295306769-127.0= .0.1:39626_-n_0000000004) [n:127.0.0.1:39626_ ] o.a.s.c.Overseer Oversee= r Loop exiting : 127.0.0.1:39626_ [junit4] 2> 1786349 WARN (zkCallback-2323-thread-1-processing-n:127.0= .0.1:39626_) [n:127.0.0.1:39626_ c:collection1 s:shard1 r:core_node4 x:coll= ection1] o.a.s.c.SyncStrategy Closed, skipping sync up. [junit4] 2> 1786349 INFO (zkCallback-2323-thread-1-processing-n:127.0= .0.1:39626_) [n:127.0.0.1:39626_ c:collection1 s:shard1 r:core_node4 x:coll= ection1] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no = versions - we can't sync in that case - we were active before, so become le= ader anyway [junit4] 2> 1786349 INFO (zkCallback-2323-thread-1-processing-n:127.0= .0.1:39626_) [n:127.0.0.1:39626_ c:collection1 s:shard1 r:core_node4 x:coll= ection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.c= ore.SolrCore@1252167 [junit4] 2> 1786359 INFO (zkCallback-2323-thread-1-processing-n:127.0= .0.1:39626_) [n:127.0.0.1:39626_ c:collection1 s:shard1 r:core_node4 x:coll= ection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.= collection1 [junit4] 2> 1786868 WARN (zkCallback-2323-thread-2-processing-n:127.0= .0.1:39626_) [n:127.0.0.1:39626_ ] o.a.s.c.c.ZkStateReader ZooKeeper wat= ch triggered, but Solr cannot talk to ZK: [KeeperErrorCode =3D Session expi= red for /live_nodes] [junit4] 2> 1786868 INFO (TEST-ShardSplitTest.testSplitAfterFailedSpl= it-seed#[12DC17AF670681F2]) [ ] o.a.s.m.SolrMetricManager Closing metric= reporters for: solr.node [junit4] 2> 1786869 INFO (TEST-ShardSplitTest.testSplitAfterFailedSpl= it-seed#[12DC17AF670681F2]) [ ] o.e.j.s.AbstractConnector Stopped Server= Connector@1cb8829{HTTP/1.1,[http/1.1]}{127.0.0.1:0} [junit4] 2> 1786869 INFO (TEST-ShardSplitTest.testSplitAfterFailedSpl= it-seed#[12DC17AF670681F2]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s= .ServletContextHandler@e9f33a{/,null,UNAVAILABLE} [junit4] 2> 1786870 INFO (TEST-ShardSplitTest.testSplitAfterFailedSpl= it-seed#[12DC17AF670681F2]) [ ] o.a.s.c.ZkTestServer connecting to 127.0= .0.1:43869 43869 [junit4] 2> 1786932 INFO (Thread-3018) [ ] o.a.s.c.ZkTestServer co= nnecting to 127.0.0.1:43869 43869 [junit4] 2> 1786933 WARN (Thread-3018) [ ] o.a.s.c.ZkTestServer Wa= tch limit violations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =096=09/solr/aliases.json [junit4] 2> =096=09/solr/clusterprops.json [junit4] 2> =095=09/solr/security.json [junit4] 2> =095=09/solr/configs/conf1 [junit4] 2> =094=09/solr/collections/collection1/state.json [junit4] 2>=20 [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2>=20 [junit4] 2> =096=09/solr/clusterstate.json [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =0938=09/solr/overseer/queue [junit4] 2> =0920=09/solr/overseer/collection-queue-work [junit4] 2> =096=09/solr/live_nodes [junit4] 2> =096=09/solr/collections [junit4] 2> =096=09/solr/overseer/queue-work [junit4] 2>=20 [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DShardSplitTes= t -Dtests.method=3DtestSplitAfterFailedSplit -Dtests.seed=3D12DC17AF670681F= 2 -Dtests.multiplier=3D3 -Dtests.slow=3Dtrue -Dtests.locale=3Dit-CH -Dtests= .timezone=3DAsia/Katmandu -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUS= -ASCII [junit4] FAILURE 30.3s J2 | ShardSplitTest.testSplitAfterFailedSplit <<< [junit4] > Throwable #1: java.lang.AssertionError: expected:<1> but w= as:<2> [junit4] > =09at __randomizedtesting.SeedInfo.seed([12DC17AF670681F2:= EB9184005B73CC78]:0) [junit4] > =09at org.apache.solr.cloud.ShardSplitTest.testSplitAfterF= ailedSplit(ShardSplitTest.java:280) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase= .java:992) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) [junit4] > =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1786936 INFO (SUITE-ShardSplitTest-seed#[12DC17AF670681F2= ]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/wo= rkspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.= ShardSplitTest_12DC17AF670681F2-001 [junit4] 2> Dec 28, 2016 8:45:35 PM com.carrotsearch.randomizedtesting= .ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thre= ad(s). [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene62): {rnd_b= =3DPostingsFormat(name=3DDirect), shard_s=3DTestBloomFilteredLucenePostings= (BloomFilteringPostingsFormat(Lucene50(blocksize=3D128))), _version_=3DTest= BloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize= =3D128))), n_ti=3DTestBloomFilteredLucenePostings(BloomFilteringPostingsFor= mat(Lucene50(blocksize=3D128))), a_t=3DTestBloomFilteredLucenePostings(Bloo= mFilteringPostingsFormat(Lucene50(blocksize=3D128))), a_i=3DPostingsFormat(= name=3DDirect), id=3DPostingsFormat(name=3DDirect)}, docValues:{}, maxPoint= sInLeafNode=3D1808, maxMBSortInHeap=3D5.63445844068737, sim=3DRandomSimilar= ity(queryNorm=3Dfalse,coord=3Dyes): {}, locale=3Dit-CH, timezone=3DAsia/Kat= mandu [junit4] 2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 1.8.0= _112 (32-bit)/cpus=3D12,threads=3D1,free=3D141797792,total=3D528482304 [junit4] 2> NOTE: All tests run in this JVM: [CleanupOldIndexTest, Tes= tMergePolicyConfig, DeleteInactiveReplicaTest, MetricsHandlerTest, MinimalS= chemaTest, SharedFSAutoReplicaFailoverUtilsTest, SolrCoreCheckLockOnStartup= Test, SolrMetricsIntegrationTest, TestRuleBasedAuthorizationPlugin, HdfsUnl= oadDistributedZkTest, TestInitQParser, NoCacheHeaderTest, AnalyticsMergeStr= ategyTest, BlockJoinFacetDistribTest, PingRequestHandlerTest, HdfsBasicDist= ributedZk2Test, DistributedFacetPivotSmallAdvancedTest, CurrencyFieldXmlFil= eTest, QueryParsingTest, TestObjectReleaseTracker, TestUnifiedSolrHighlight= er, TestSort, TestFieldCacheSanityChecker, TestDefaultStatsCache, LukeReque= stHandlerTest, JSONWriterTest, CdcrRequestHandlerTest, TestSchemaVersionRes= ource, GraphQueryTest, MergeStrategyTest, RecoveryZkTest, TestCloudPseudoRe= turnFields, TestCollectionAPI, TestJsonRequest, TestMacros, TestManagedReso= urceStorage, TestOnReconnectListenerSupport, TestGroupingSearch, RemoteQuer= yErrorTest, SuggesterTSTTest, TestLFUCache, TestConfigSets, TestRestoreCore= , StatsReloadRaceTest, CoreAdminRequestStatusTest, FieldMutatingUpdateProce= ssorTest, FullSolrCloudDistribCmdsTest, TestIndexingPerformance, TestSolrCo= nfigHandlerCloud, DistribCursorPagingTest, LeaderFailoverAfterPartitionTest= , CircularListTest, PluginInfoTest, TestRecoveryHdfs, FacetPivotSmallTest, = SolrJmxReporterTest, CoreAdminHandlerTest, BadComponentTest, TestDistribute= dStatsComponentCardinality, ForceLeaderTest, BinaryUpdateRequestHandlerTest= , TestDistributedMissingSort, DocExpirationUpdateProcessorFactoryTest, Assi= gnTest, TestPushWriter, TestFieldCacheVsDocValues, AddSchemaFieldsUpdatePro= cessorFactoryTest, TestImpersonationWithHadoopAuth, BigEndianAscendingWordS= erializerTest, PrimUtilsTest, TestDocTermOrds, TestAnalyzedSuggestions, Tes= tXIncludeConfig, TestShardHandlerFactory, ConnectionReuseTest, TestJmxMonit= oredMap, HdfsSyncSliceTest, TestRandomCollapseQParserPlugin, ChangedSchemaM= ergeTest, EnumFieldTest, TestCollationField, RequestLoggingTest, DebugCompo= nentTest, TestReload, TestMiniSolrCloudCluster, SimpleFacetsTest, UpdatePar= amsTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestIndexSearcher, TestE= xactStatsCache, PreAnalyzedFieldTest, TestPerFieldSimilarityClassic, CdcrBo= otstrapTest, DirectUpdateHandlerOptimizeTest, SparseHLLTest, TestManagedSch= ema, TestSolrQueryResponse, TestJsonFacetRefinement, TestManagedSynonymFilt= erFactory, TestSolrFieldCacheMBean, TestStressReorder, XmlUpdateRequestHand= lerTest, DataDrivenBlockJoinTest, TestTrieFacet, CollectionStateFormat2Test= , TestNumericTerms32, TestGeoJSONResponseWriter, OverseerTaskQueueTest, Tes= tJoin, SolrCoreTest, SpellCheckComponentTest, BadIndexSchemaTest, Converted= LegacyTest, TestFunctionQuery, BasicFunctionalityTest, TestLazyCores, Direc= tUpdateHandlerTest, ShowFileRequestHandlerTest, DistributedQueryElevationCo= mponentTest, SolrIndexSplitterTest, TestCoreDiscovery, AnalysisAfterCoreRel= oadTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, Sug= gesterFSTTest, SpatialFilterTest, SuggesterWFSTTest, SchemaVersionSpecificB= ehaviorTest, QueryEqualityTest, StatelessScriptUpdateProcessorFactoryTest, = TestSolrDeletionPolicy1, TestReversedWildcardFilterFactory, DocumentAnalysi= sRequestHandlerTest, TestOmitPositions, RequestHandlersTest, RequiredFields= Test, TestSolrQueryParser, SolrPluginUtilsTest, TestPHPSerializedResponseWr= iter, SOLR749Test, ResponseLogComponentTest, TestStressRecovery, ZkNodeProp= sTest, CursorPagingTest, AliasIntegrationTest, CollectionReloadTest, Config= SetsAPITest, ConnectionManagerTest, DeleteLastCustomShardedReplicaTest, Del= eteNodeTest, DeleteReplicaTest, DistributedQueueTest, DistributedVersionInf= oTest, LeaderInitiatedRecoveryOnCommitTest, OverriddenZkACLAndCredentialsPr= ovidersTest, OverseerModifyCollectionTest, ReplicationFactorTest, RestartWh= ileUpdatingTest, SSLMigrationTest, SaslZkACLProviderTest, ShardSplitTest] [junit4] Completed [581/675 (2!)] on J2 in 413.27s, 5 tests, 1 failure <= << FAILURES! [...truncated 54948 lines...] ------=_Part_352_1176838328.1482958695764 Content-Type: text/plain; charset=us-ascii --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscribe@lucene.apache.org For additional commands, e-mail: dev-help@lucene.apache.org ------=_Part_352_1176838328.1482958695764--