Return-Path: X-Original-To: apmail-lucene-dev-archive@www.apache.org Delivered-To: apmail-lucene-dev-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 45C6718247 for ; Sat, 20 Jun 2015 17:18:52 +0000 (UTC) Received: (qmail 7472 invoked by uid 500); 20 Jun 2015 17:18:51 -0000 Delivered-To: apmail-lucene-dev-archive@lucene.apache.org Received: (qmail 7423 invoked by uid 500); 20 Jun 2015 17:18:51 -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 7413 invoked by uid 99); 20 Jun 2015 17:18:50 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 20 Jun 2015 17:18:50 +0000 X-ASF-Spam-Status: No, hits=0.0 required=5.0 tests=FSL_HELO_NON_FQDN_1,SPF_PASS,WEIRD_PORT X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: domain of jenkins@thetaphi.de designates 188.138.57.78 as permitted sender) Received: from [188.138.57.78] (HELO serv1) (188.138.57.78) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 20 Jun 2015 17:16:28 +0000 Received: from localhost.localdomain ([127.0.0.1] helo=serv1) by serv1 with esmtp (Exim 4.82) (envelope-from ) id 1Z6MPH-0006MR-Vo for dev@lucene.apache.org; Sat, 20 Jun 2015 17:18:16 +0000 Date: Sat, 20 Jun 2015 17:17:59 +0000 (UTC) From: Policeman Jenkins Server To: dev@lucene.apache.org Message-ID: <1481357622.91.1434820695984.JavaMail.jenkins@serv1> Subject: [JENKINS] Lucene-Solr-5.x-Linux (64bit/jdk1.7.0_80) - Build # 12960 - Failure! MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="----=_Part_90_2118563494.1434820695941" X-Jenkins-Job: Lucene-Solr-5.x-Linux X-Jenkins-Result: FAILURE X-Virus-Checked: Checked by ClamAV on apache.org ------=_Part_90_2118563494.1434820695941 Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/12960/ Java: 64bit/jdk1.7.0_80 -XX:-UseCompressedOops -XX:+UseSerialGC 1 tests failed. FAILED: org.apache.solr.search.mlt.CloudMLTQParserTest.test Error Message: java.lang.String cannot be cast to java.util.ArrayList Stack Trace: java.lang.ClassCastException: java.lang.String cannot be cast to java.util.= ArrayList =09at __randomizedtesting.SeedInfo.seed([B8961174A8583955:30C22EAE06A454AD]= :0) =09at org.apache.solr.search.mlt.CloudMLTQParserTest.test(CloudMLTQParserTe= st.java:126) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.= java:57) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces= sorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:606) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(Randomized= Runner.java:1627) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(Random= izedRunner.java:836) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(Random= izedRunner.java:872) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(Random= izedRunner.java:886) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= FixedStatement.callStatement(BaseDistributedSearchTestCase.java:960) =09at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$Shards= Statement.evaluate(BaseDistributedSearchTestCase.java:935) =09at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$= 1.evaluate(SystemPropertiesRestoreRule.java:57) =09at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRu= leSetupTeardownChained.java:50) =09at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBef= oreAfterRule.java:46) =09at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleT= hreadAndTestName.java:49) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:65) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:365) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTa= sk(ThreadLeakControl.java:798) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(Threa= dLeakControl.java:458) =09at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(Ran= domizedRunner.java:845) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(Random= izedRunner.java:747) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(Random= izedRunner.java:781) =09at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(Random= izedRunner.java:792) =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:46) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStor= eClassName.java:42) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =09at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMeth= odsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) =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:54) =09at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFai= lure.java:48) =09at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(Test= RuleIgnoreAfterMaxFailures.java:65) =09at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIg= noreTestSuites.java:55) =09at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(St= atementAdapter.java:36) =09at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.= run(ThreadLeakControl.java:365) =09at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 10451 lines...] [junit4] Suite: org.apache.solr.search.mlt.CloudMLTQParserTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-5.x-= Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest= _B8961174A8583955-001/init-core-data-001 [junit4] 2> 919405 INFO (SUITE-CloudMLTQParserTest-seed#[B8961174A858= 3955]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and client= Auth (false) [junit4] 2> 919405 INFO (SUITE-CloudMLTQParserTest-seed#[B8961174A858= 3955]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContex= t system property: / [junit4] 2> 919410 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERV= ER [junit4] 2> 919410 INFO (Thread-2273) [ ] o.a.s.c.ZkTestServer cli= ent port:0.0.0.0/0.0.0.0:0 [junit4] 2> 919411 INFO (Thread-2273) [ ] o.a.s.c.ZkTestServer Sta= rting server [junit4] 2> 919510 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.ZkTestServer start zk server on po= rt:44986 [junit4] 2> 919511 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCre= dentialsProvider [junit4] 2> 919511 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Waiting for cl= ient to connect to ZooKeeper [junit4] 2> 919514 INFO (zkCallback-557-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@71ac= 5a30 name:ZooKeeperConnection Watcher:127.0.0.1:44986 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 919514 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Client is conn= ected to ZooKeeper [junit4] 2> 919515 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACL= Provider [junit4] 2> 919515 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 919516 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCre= dentialsProvider [junit4] 2> 919518 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Waiting for cl= ient to connect to ZooKeeper [junit4] 2> 919519 INFO (zkCallback-558-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1778= 324a name:ZooKeeperConnection Watcher:127.0.0.1:44986/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 919519 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Client is conn= ected to ZooKeeper [junit4] 2> 919520 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACL= Provider [junit4] 2> 919520 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /collecti= ons/collection1 [junit4] 2> 919522 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /collecti= ons/collection1/shards [junit4] 2> 919522 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /collecti= ons/control_collection [junit4] 2> 919523 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /collecti= ons/control_collection/shards [junit4] 2> 919524 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenki= ns/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection= 1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml [junit4] 2> 919524 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/= conf1/solrconfig.xml [junit4] 2> 919526 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenki= ns/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection= 1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 919526 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/= conf1/schema.xml [junit4] 2> 919527 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenki= ns/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection= 1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfi= g.snippet.randomindexconfig.xml [junit4] 2> 919528 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/= conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 919529 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenki= ns/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection= 1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 919529 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/= conf1/stopwords.txt [junit4] 2> 919531 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenki= ns/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection= 1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 919531 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/= conf1/protwords.txt [junit4] 2> 919532 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenki= ns/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection= 1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 919532 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/= conf1/currency.xml [junit4] 2> 919533 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenki= ns/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection= 1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 919533 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/= conf1/enumsConfig.xml [junit4] 2> 919534 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenki= ns/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection= 1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 919534 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/= conf1/open-exchange-rates.json [junit4] 2> 919535 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenki= ns/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection= 1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accen= t.txt [junit4] 2> 919535 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/= conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 919536 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenki= ns/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection= 1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 919536 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/= conf1/old_synonyms.txt [junit4] 2> 919537 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenki= ns/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection= 1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 919537 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/= conf1/synonyms.txt [junit4] 2> 919610 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properti= es file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-co= re/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/co= ntrol-001/cores/collection1 [junit4] 2> 919611 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 919613 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.S= ervletContextHandler@29638112{/,null,AVAILABLE} [junit4] 2> 919614 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.e.j.s.ServerConnector Started ServerConn= ector@c684b7f{HTTP/1.1}{127.0.0.1:40619} [junit4] 2> 919614 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.e.j.s.Server Started @921547ms [junit4] 2> 919614 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properti= es: {hostContext=3D/, solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-5= .x-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserT= est_B8961174A8583955-001/tempDir-001/control/data, hostPort=3D40619, coreRo= otDirectory=3D/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr= -core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001= /control-001/cores} [junit4] 2> 919615 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFil= ter.init(): sun.misc.Launcher$AppClassLoader@3b756db3 [junit4] 2> 919615 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.SolrResourceLoader new SolrResourc= eLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/= build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8= 583955-001/control-001/' [junit4] 2> 919630 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.SolrXmlConfig Loading container co= nfiguration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/s= olr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-= 001/control-001/solr.xml [junit4] 2> 919642 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.CoresLocator Config-defined core r= oot directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/sol= r-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-00= 1/control-001/cores [junit4] 2> 919642 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.CoreContainer New CoreContainer 10= 2288817 [junit4] 2> 919642 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.CoreContainer Loading cores into C= oreContainer [instanceDir=3D/home/jenkins/workspace/Lucene-Solr-5.x-Linux/s= olr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B89611= 74A8583955-001/control-001/] [junit4] 2> 919643 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.CoreContainer loading shared libra= ry: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test= /J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/control-0= 01/lib [junit4] 2> 919643 WARN (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.SolrResourceLoader Can't find (or = read) directory to add to classloader: lib (resolved as: /home/jenkins/work= space/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.m= lt.CloudMLTQParserTest_B8961174A8583955-001/control-001/lib). [junit4] 2> 919650 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.h.c.HttpShardHandlerFactory created = with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsP= erHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 21474= 83647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetr= ies : false, [junit4] 2> 919652 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.u.UpdateShardHandler Creating Update= ShardHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D= 45000&retry=3Dtrue [junit4] 2> 919652 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j= .impl.Log4jLoggerFactory [junit4] 2> 919652 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.l.LogWatcher Registering Log Listene= r [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 919653 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127= .0.0.1:44986/solr [junit4] 2> 919653 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.ZkController zkHost includes chroo= t [junit4] 2> 919653 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCre= dentialsProvider [junit4] 2> 919653 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Waiting for cl= ient to connect to ZooKeeper [junit4] 2> 919654 INFO (zkCallback-560-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c83= a4c9 name:ZooKeeperConnection Watcher:127.0.0.1:44986 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 919655 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Client is conn= ected to ZooKeeper [junit4] 2> 919655 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACL= Provider [junit4] 2> 919657 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.ConnectionMana= ger Waiting for client to connect to ZooKeeper [junit4] 2> 919658 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.c.ConnectionManager Watcher org= .apache.solr.common.cloud.ConnectionManager@3d798970 name:ZooKeeperConnecti= on Watcher:127.0.0.1:44986/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4] 2> 919658 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.ConnectionMana= ger Client is connected to ZooKeeper [junit4] 2> 919659 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /overseer/queue [junit4] 2> 919660 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /overseer/collection-queue-work [junit4] 2> 919660 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /overseer/collection-map-running [junit4] 2> 919662 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /overseer/collection-map-completed [junit4] 2> 919663 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /overseer/collection-map-failure [junit4] 2> 919663 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /live_nodes [junit4] 2> 919664 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /aliases.json [junit4] 2> 919665 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /clusterstate.json [junit4] 2> 919665 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.ZkController Reg= ister node as live in ZooKeeper:/live_nodes/127.0.0.1:40619_ [junit4] 2> 919666 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /live_nodes/127.0.0.1:40619_ [junit4] 2> 919666 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /overseer_elect [junit4] 2> 919667 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /overseer_elect/election [junit4] 2> 919667 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.Overseer Oversee= r (id=3Dnull) closing [junit4] 2> 919668 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.LeaderElector Jo= ined leadership election with path: /overseer_elect/election/94032361050275= 843-127.0.0.1:40619_-n_0000000000 [junit4] 2> 919668 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.OverseerElection= Context I am going to be the leader 127.0.0.1:40619_ [junit4] 2> 919668 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /overseer_elect/leader [junit4] 2> 919669 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.Overseer Oversee= r (id=3D94032361050275843-127.0.0.1:40619_-n_0000000000) starting [junit4] 2> 919670 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.SolrZkClient m= akePath: /overseer/queue-work [junit4] 2> 919672 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.OverseerAutoRepl= icaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFai= loverWorkLoopDelay=3D10000 autoReplicaFailoverWaitAfterExpiration=3D30000 a= utoReplicaFailoverBadNodeExpiration=3D60000 [junit4] 2> 919673 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.c.ZkStateReader = Updating cluster state from ZooKeeper...=20 [junit4] 2> 919673 INFO (OverseerCollectionProcessor-9403236105027584= 3-127.0.0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.OverseerC= ollectionProcessor Process current queue of collection creations [junit4] 2> 919673 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.Overseer Starting= to work on the main queue [junit4] 2> 919675 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.CoreContainer No= authentication plugin used. [junit4] 2> 919675 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.CoreContainer Se= curity conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 919676 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.CoresLocator Loo= king for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.= x-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTe= st_B8961174A8583955-001/control-001/cores [junit4] 2> 919677 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.SolrCore Created= CoreDescriptor: {absoluteInstDir=3D/home/jenkins/workspace/Lucene-Solr-5.x= -Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTes= t_B8961174A8583955-001/control-001/cores/collection1/, schema=3Dschema.xml,= loadOnStartup=3Dtrue, shard=3D, collection=3Dcontrol_collection, dataDir= =3Ddata/, coreNodeName=3D, instanceDir=3D/home/jenkins/workspace/Lucene-Sol= r-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQPars= erTest_B8961174A8583955-001/control-001/cores/collection1, name=3Dcollectio= n1, transient=3Dfalse, config=3Dsolrconfig.xml} [junit4] 2> 919677 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.CoresLocator Fou= nd core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/b= uild/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A85= 83955-001/control-001/cores/collection1/ [junit4] 2> 919677 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.c.CoresLocator Fou= nd 1 core definitions [junit4] 2> 919677 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.ZkController publishing state=3Ddown [junit4] 2> 919677 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.s.SolrDispatchFilt= er user.dir=3D/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr= -core/test/J1 [junit4] 2> 919678 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:40619_ ] o.a.s.s.SolrDispatchFilt= er SolrDispatchFilter.init() done [junit4] 2> 919677 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.ZkController numShards not found on descriptor - reading it from s= ystem property [junit4] 2> 919678 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.ZkController look for our core node name [junit4] 2> 919678 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.DistributedQueue NodeChildrenCh= anged fired on path /overseer/queue state SyncConnected [junit4] 2> 919679 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.Overseer processM= essage: queueSize: 1, message =3D { [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:40619_", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:40619"} current state versi= on: 0 [junit4] 2> 919679 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.o.ReplicaMutator = Update state numShards=3D1 message=3D{ [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:40619_", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:40619"} [junit4] 2> 919679 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.o.ClusterStateMut= ator building a new cName: control_collection [junit4] 2> 919679 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.o.ReplicaMutator = Assigning new node to shard shard=3Dshard1 [junit4] 2> 919680 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.c.ZkStateReader A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 919681 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.c.ZkStateReader Updated cluster= state version to 1 [junit4] 2> 920678 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.ZkController waiting to find shard id in clusterstate for collecti= on1 [junit4] 2> 920679 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 920679 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 920679 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_c= ollection [junit4] 2> 920679 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.c.ZkStateReader path=3D/collections/control_collection configName= =3Dconf1 specified config exists in ZooKeeper [junit4] 2> 920680 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/home/je= nkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/sol= r.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/control-001/cores/col= lection1/' [junit4] 2> 920695 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 920702 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 920718 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0 [junit4] 2> 920753 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 920754 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 920761 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 920973 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchang= e-rates.json, refreshInterval=3D1440. [junit4] 2> 920979 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 920980 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.IndexSchema unique key field: id [junit4] 2> 920984 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file curren= cy.xml [junit4] 2> 920986 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file curren= cy.xml [junit4] 2> 920988 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-ex= change-rates.json [junit4] 2> 920989 WARN (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 920989 WARN (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 920989 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-ex= change-rates.json [junit4] 2> 920989 WARN (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 920990 WARN (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 920990 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection x:collection1]= o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration = from collection control_collection [junit4] 2> 920990 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFac= tory [junit4] 2> 920990 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore= at [/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/tes= t/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/control-= 001/cores/collection1/], dataDir=3D[null] [junit4] 2> 920990 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Add= ing Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@57d3e= 40f [junit4] 2> 920991 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory f= or /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/= J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/control-00= 1/cores/collection1/data [junit4] 2> 920991 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=3Dn= ull new=3D/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-cor= e/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/con= trol-001/cores/collection1/data/index/ [junit4] 2> 920991 WARN (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/= home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/t= emp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/control-001/co= res/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 920991 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory f= or /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/= J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/control-00= 1/cores/collection1/data/index [junit4] 2> 920992 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping c= lass org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMerg= eAtOnce=3D23, maxMergeAtOnceExplicit=3D25, maxMergedSegmentMB=3D71.44335937= 5, floorSegmentMB=3D0.4189453125, forceMergeDeletesPctAllowed=3D28.00468720= 3221273, segmentsPerTier=3D33.0, maxCFSSegmentSizeMB=3D8.796093022207999E12= , noCFSRatio=3D1.0 [junit4] 2> 920992 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits:= num=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@71fdd4b2= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@4f972c95),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 920993 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore newest commit generation =3D 1 [junit4] 2> 920993 INFO (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:col= lection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for= core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/bui= ld/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583= 955-001/control-001/cores/collection1/data/ [junit4] 2> 920994 WARN (OldIndexDirectoryCleanupThreadForCore-collec= tion1) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:col= lection1] o.a.s.c.DirectoryFactory /home/jenkins/workspace/Lucene-Solr-5.x-= Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest= _B8961174A8583955-001/control-001/cores/collection1/data/ does not point to= a valid data directory; skipping clean-up of old index directories. [junit4] 2> 920998 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "dedupe" [junit4] 2> 920998 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distri= butedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 920998 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "dedupe-allfields" [junit4] 2> 920998 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distri= butedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfie= lds" [junit4] 2> 920999 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "stored_sig" [junit4] 2> 920999 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distri= butedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 920999 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "uniq-fields" [junit4] 2> 920999 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distri= butedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 920999 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 921000 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateR= equestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 921000 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting Distri= butedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-t= est-chain-implicit" [junit4] 2> 921000 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain define= d as default, creating implicit default [junit4] 2> 921003 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 921004 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 921006 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 921007 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=3D60 [junit4] 2> 921011 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with= startup=3Dlazy=20 [junit4] 2> 921013 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: mock,lazy,/a= dmin/system,spellCheckCompRH1,/admin/luke,dismax,/search-facet-invariants,/= admin/file,/get,spellCheckWithWordbreak_Direct,/admin/properties,/update,/a= dmin/ping,standard,/debug/dump,spellCheckCompRH,defaults,/admin/mbeans,/ter= ms,/update/json,spellCheckCompRH_Direct,/schema,/config,/replication,spellC= heckWithWordbreak,/admin/plugins,mltrh,/search-facet-def,/update/json/docs,= /update/csv,/mlt,/admin/threads,/admin/logging,/admin/segments,tvrh [junit4] 2> 921014 WARN (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated , Please update your config to use the ShowFileRequestHandle= r. [junit4] 2> 921015 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.a= pache.solr.search.stats.LocalStatsCache [junit4] 2> 921015 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: = org.apache.solr.update.UpdateLog [junit4] 2> 921015 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=3D d= efaultSyncLevel=3DFLUSH numRecordsToKeep=3D100 maxNumLogsToKeep=3D10 numVer= sionBuckets=3D65536 [junit4] 2> 921016 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 921016 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 921017 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping c= lass org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolic= y: minMergeSize=3D1677721, mergeFactor=3D28, maxMergeSize=3D2147483648, max= MergeSizeForForcedMerge=3D9223372036854775807, calibrateSizeByDeletes=3Dtru= e, maxMergeDocs=3D2147483647, maxCFSSegmentSizeMB=3D8.796093022207999E12, n= oCFSRatio=3D1.0] [junit4] 2> 921017 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: n= um=3D1 [junit4] 2> =09commit{dir=3DMockDirectoryWrapper(RAMDirectory@71fdd4b2= lockFactory=3Dorg.apache.lucene.store.SingleInstanceLockFactory@4f972c95),= segFN=3Dsegments_1,generation=3D1} [junit4] 2> 921017 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.SolrCore newest commit generation =3D 1 [junit4] 2> 921018 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6e3819fa[co= llection1] main [junit4] 2> 921018 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/c= ollections/control_collection [junit4] 2> 921018 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.c.ZkStateReader path=3D/collections/control_co= llection configName=3Dconf1 specified config exists in ZooKeeper [junit4] 2> 921019 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with init= Args: {storageIO=3Dorg.apache.solr.rest.ManagedResourceStorage$InMemoryStor= ageIO} [junit4] 2> 921019 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.j= son using InMemoryStorage [junit4] 2> 921019 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedR= esources [junit4] 2> 921019 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell check= ers [junit4] 2> 921049 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=3Ddirect,cl= assname=3DDirectSolrSpellChecker,field=3Dlowerfilt,minQueryLength=3D3} [junit4] 2> 921151 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved fo= r 10000 [junit4] 2> 921151 INFO (searcherExecutor-2721-thread-1-processing-x:= collection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) = [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: defa= ult [junit4] 2> 921152 INFO (searcherExecutor-2721-thread-1-processing-x:= collection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) = [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: dire= ct [junit4] 2> 921152 INFO (searcherExecutor-2721-thread-1-processing-x:= collection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) = [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: word= break [junit4] 2> 921152 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version fiel= d to seed version buckets [junit4] 2> 921152 INFO (searcherExecutor-2721-thread-1-processing-x:= collection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) = [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: mult= ipleFields [junit4] 2> 921152 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _versi= on_ for 65536 version buckets from index [junit4] 2> 921153 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cann= ot seed version bucket highest value from index [junit4] 2> 921153 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index = or recent updates, using new clock 1504517778629984256 [junit4] 2> 921153 INFO (searcherExecutor-2721-thread-1-processing-x:= collection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) = [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jaro= winkler [junit4] 2> 921154 INFO (searcherExecutor-2721-thread-1-processing-x:= collection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) = [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: exte= rnal [junit4] 2> 921154 INFO (searcherExecutor-2721-thread-1-processing-x:= collection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) = [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 921154 INFO (searcherExecutor-2721-thread-1-processing-x:= collection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) = [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 921155 INFO (searcherExecutor-2721-thread-1-processing-x:= collection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) = [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perD= ict [junit4] 2> 921155 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.u.UpdateLog Took 3 ms to seed version buckets wi= th highest version 1504517778629984256 [junit4] 2> 921155 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 921155 INFO (searcherExecutor-2721-thread-1-processing-x:= collection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) = [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection= 1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6e3819fa= [collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 921156 INFO (coreLoadExecutor-2720-thread-1-processing-n:= 127.0.0.1:40619_) [n:127.0.0.1:40619_ c:control_collection s:shard1 r:core_= node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 921156 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController Register replica - core:collection1 address:http://12= 7.0.0.1:40619 collection:control_collection shard:shard1 [junit4] 2> 921157 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_el= ect/shard1/election [junit4] 2> 921158 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.LeaderElector Joined leadership election with path: /collections/c= ontrol_collection/leader_elect/shard1/election/94032361050275843-core_node1= -n_0000000000 [junit4] 2> 921159 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ShardLeaderElectionContext Running the leader process for shard sh= ard1 [junit4] 2> 921160 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.DistributedQueue NodeChildrenCh= anged fired on path /overseer/queue state SyncConnected [junit4] 2> 921160 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 921160 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 921160 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:40619/collection1/ [junit4] 2> 921160 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.Overseer processM= essage: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version= : 1 [junit4] 2> 921160 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 921160 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.SyncStrategy http://127.0.0.1:40619/collection1/ has no replicas [junit4] 2> 921160 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:4= 0619/collection1/ shard1 [junit4] 2> 921160 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/s= hard1 [junit4] 2> 921163 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.DistributedQueue NodeChildrenCh= anged fired on path /overseer/queue state SyncConnected [junit4] 2> 921163 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.Overseer processM= essage: queueSize: 1, message =3D { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:40619", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 921179 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCre= dentialsProvider [junit4] 2> 921180 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Waiting for cl= ient to connect to ZooKeeper [junit4] 2> 921181 INFO (zkCallback-563-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@68ae= 1e32 name:ZooKeeperConnection Watcher:127.0.0.1:44986/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 921181 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Client is conn= ected to ZooKeeper [junit4] 2> 921181 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACL= Provider [junit4] 2> 921181 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ZkStateReader Updating cluster s= tate from ZooKeeper...=20 [junit4] 2> 921183 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire = sessions:false cause connection loss:false [junit4] 2> 921183 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Crea= ting collection1 with stateFormat=3D2 [junit4] 2> 921183 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCre= dentialsProvider [junit4] 2> 921183 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Waiting for cl= ient to connect to ZooKeeper [junit4] 2> 921184 INFO (zkCallback-564-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6c44= 15bd name:ZooKeeperConnection Watcher:127.0.0.1:44986/solr got event Watche= dEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 921184 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Client is conn= ected to ZooKeeper [junit4] 2> 921184 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACL= Provider [junit4] 2> 921186 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.DistributedQueue NodeChildrenCh= anged fired on path /overseer/queue state SyncConnected [junit4] 2> 921187 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.Overseer processM= essage: queueSize: 1, message =3D { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 1 [junit4] 2> 921187 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.o.ClusterStateMut= ator building a new cName: collection1 [junit4] 2> 921187 INFO (zkCallback-563-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: WatchedEvent state:SyncConnected type:N= odeDataChanged path:/clusterstate.json, has occurred - updating... (live no= des size: 1) [junit4] 2> 921187 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.c.ZkStateReader A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 921190 INFO (zkCallback-563-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated cluster state version to 2 [junit4] 2> 921190 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.c.ZkStateReader Updated cluster= state version to 2 [junit4] 2> 921213 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController We are http://127.0.0.1:40619/collection1/ and leader= is http://127.0.0.1:40619/collection1/ [junit4] 2> 921213 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController No LogReplay needed for core=3Dcollection1 baseURL=3D= http://127.0.0.1:40619 [junit4] 2> 921214 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 921214 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController publishing state=3Dactive [junit4] 2> 921214 INFO (coreZkRegister-2714-thread-1-processing-x:co= llection1 n:127.0.0.1:40619_ r:core_node1 s:shard1 c:control_collection) [n= :127.0.0.1:40619_ c:control_collection s:shard1 r:core_node1 x:collection1]= o.a.s.c.ZkController numShards not found on descriptor - reading it from s= ystem property [junit4] 2> 921215 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.DistributedQueue NodeChildrenCh= anged fired on path /overseer/queue state SyncConnected [junit4] 2> 921215 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.Overseer processM= essage: queueSize: 1, message =3D { [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "state":"active", [junit4] 2> "node_name":"127.0.0.1:40619_", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:40619"} current state versi= on: 2 [junit4] 2> 921215 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.o.ReplicaMutator = Update state numShards=3D2 message=3D{ [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "state":"active", [junit4] 2> "node_name":"127.0.0.1:40619_", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:40619"} [junit4] 2> 921216 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.o.ZkStateWriter g= oing to create_collection /collections/collection1/state.json [junit4] 2> 921216 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.c.ZkStateReader A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 921216 INFO (zkCallback-563-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: WatchedEvent state:SyncConnected type:N= odeDataChanged path:/clusterstate.json, has occurred - updating... (live no= des size: 1) [junit4] 2> 921217 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.c.ZkStateReader Updated cluster= state version to 3 [junit4] 2> 921217 INFO (zkCallback-563-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated cluster state version to 3 [junit4] 2> 921260 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properti= es file to /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-co= re/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/sh= ard-1-001/cores/collection1 [junit4] 2> 921261 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.AbstractFullDistribZkTestBase crea= te jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/= build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8= 583955-001/shard-1-001 [junit4] 2> 921262 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 921264 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.S= ervletContextHandler@1f925734{/,null,AVAILABLE} [junit4] 2> 921264 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.e.j.s.ServerConnector Started ServerConn= ector@3a9ea56{HTTP/1.1}{127.0.0.1:34315} [junit4] 2> 921265 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.e.j.s.Server Started @923198ms [junit4] 2> 921265 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properti= es: {hostPort=3D34315, coreRootDirectory=3D/home/jenkins/workspace/Lucene-S= olr-5.x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/= jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/s= olr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/shard-1-001/cores, = hostContext=3D/, solr.data.dir=3D/home/jenkins/workspace/Lucene-Solr-5.x-Li= nux/solr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B= 8961174A8583955-001/tempDir-001/jetty1, solrconfig=3Dsolrconfig.xml} [junit4] 2> 921265 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFil= ter.init(): sun.misc.Launcher$AppClassLoader@3b756db3 [junit4] 2> 921265 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.SolrResourceLoader new SolrResourc= eLoader for directory: '/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/= build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/L= ucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.Clou= dMLTQParserTest_B8961174A8583955-001/shard-1-001/' [junit4] 2> 921298 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.SolrXmlConfig Loading container co= nfiguration from /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/s= olr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-S= olr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQPa= rserTest_B8961174A8583955-001/shard-1-001/solr.xml [junit4] 2> 921312 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.CoresLocator Config-defined core r= oot directory: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/sol= r-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Sol= r-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQPars= erTest_B8961174A8583955-001/shard-1-001/cores [junit4] 2> 921312 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.CoreContainer New CoreContainer 16= 79864495 [junit4] 2> 921312 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.CoreContainer Loading cores into C= oreContainer [instanceDir=3D/home/jenkins/workspace/Lucene-Solr-5.x-Linux/s= olr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspa= ce/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.= CloudMLTQParserTest_B8961174A8583955-001/shard-1-001/] [junit4] 2> 921312 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.CoreContainer loading shared libra= ry: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test= /J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux= /solr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B896= 1174A8583955-001/shard-1-001/lib [junit4] 2> 921312 WARN (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.SolrResourceLoader Can't find (or = read) directory to add to classloader: lib (resolved as: /home/jenkins/work= space/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/../../../../../../= ../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/= test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/shard= -1-001/lib). [junit4] 2> 921318 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.c.ZkStateReader A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/cluste= rstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 921318 INFO (zkCallback-563-thread-1) [ ] o.a.s.c.c.Zk= StateReader A cluster state change: WatchedEvent state:SyncConnected type:N= odeDataChanged path:/clusterstate.json, has occurred - updating... (live no= des size: 1) [junit4] 2> 921319 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.c.ZkStateReader Updated cluster= state version to 4 [junit4] 2> 921319 INFO (zkCallback-563-thread-1) [ ] o.a.s.c.c.Zk= StateReader Updated cluster state version to 4 [junit4] 2> 921320 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.h.c.HttpShardHandlerFactory created = with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsP= erHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 21474= 83647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetr= ies : false, [junit4] 2> 921321 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.u.UpdateShardHandler Creating Update= ShardHandler HTTP client with params: socketTimeout=3D340000&connTimeout=3D= 45000&retry=3Dtrue [junit4] 2> 921322 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j= .impl.Log4jLoggerFactory [junit4] 2> 921322 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.l.LogWatcher Registering Log Listene= r [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 921322 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.ZkContainer Zookeeper client=3D127= .0.0.1:44986/solr [junit4] 2> 921322 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.ZkController zkHost includes chroo= t [junit4] 2> 921322 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCre= dentialsProvider [junit4] 2> 921323 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Waiting for cl= ient to connect to ZooKeeper [junit4] 2> 921323 INFO (zkCallback-566-thread-1) [ ] o.a.s.c.c.Co= nnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7616= 8071 name:ZooKeeperConnection Watcher:127.0.0.1:44986 got event WatchedEven= t state:SyncConnected type:None path:null path:null type:None [junit4] 2> 921324 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.ConnectionManager Client is conn= ected to ZooKeeper [junit4] 2> 921324 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACL= Provider [junit4] 2> 921325 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.c.ConnectionMana= ger Waiting for client to connect to ZooKeeper [junit4] 2> 921326 INFO (zkCallback-567-thread-1-processing-n:127.0.0= .1:34315_) [n:127.0.0.1:34315_ ] o.a.s.c.c.ConnectionManager Watcher org= .apache.solr.common.cloud.ConnectionManager@7e83af9f name:ZooKeeperConnecti= on Watcher:127.0.0.1:44986/solr got event WatchedEvent state:SyncConnected = type:None path:null path:null type:None [junit4] 2> 921326 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.c.ConnectionMana= ger Client is connected to ZooKeeper [junit4] 2> 921328 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.c.ZkStateReader = Updating cluster state from ZooKeeper...=20 [junit4] 2> 922331 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.ZkController Reg= ister node as live in ZooKeeper:/live_nodes/127.0.0.1:34315_ [junit4] 2> 922331 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.c.SolrZkClient m= akePath: /live_nodes/127.0.0.1:34315_ [junit4] 2> 922333 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.Overseer Oversee= r (id=3Dnull) closing [junit4] 2> 922334 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.LeaderElector Jo= ined leadership election with path: /overseer_elect/election/94032361050275= 847-127.0.0.1:34315_-n_0000000001 [junit4] 2> 922334 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.LeaderElector Wa= tching path /overseer_elect/election/94032361050275843-127.0.0.1:40619_-n_0= 000000000 to know if I could be the leader [junit4] 2> 922334 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.CoreContainer No= authentication plugin used. [junit4] 2> 922335 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.CoreContainer Se= curity conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 922335 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.CoresLocator Loo= king for core definitions underneath /home/jenkins/workspace/Lucene-Solr-5.= x-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkin= s/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.se= arch.mlt.CloudMLTQParserTest_B8961174A8583955-001/shard-1-001/cores [junit4] 2> 922336 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.SolrCore Created= CoreDescriptor: {coreNodeName=3D, collection=3Dcollection1, dataDir=3Ddata= /, transient=3Dfalse, schema=3Dschema.xml, name=3Dcollection1, shard=3D, ab= soluteInstDir=3D/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/so= lr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-So= lr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.CloudMLTQPar= serTest_B8961174A8583955-001/shard-1-001/cores/collection1/, config=3Dsolrc= onfig.xml, instanceDir=3D/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr= /build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/= Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.Clo= udMLTQParserTest_B8961174A8583955-001/shard-1-001/cores/collection1, loadOn= Startup=3Dtrue} [junit4] 2> 922336 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.CoresLocator Fou= nd core collection1 in /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/b= uild/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lu= cene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.mlt.Cloud= MLTQParserTest_B8961174A8583955-001/shard-1-001/cores/collection1/ [junit4] 2> 922336 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.c.CoresLocator Fou= nd 1 core definitions [junit4] 2> 922337 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.s.SolrDispatchFilt= er user.dir=3D/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr= -core/test/J1 [junit4] 2> 922337 INFO (TEST-CloudMLTQParserTest.testInvalidDocument= -seed#[B8961174A8583955]) [n:127.0.0.1:34315_ ] o.a.s.s.SolrDispatchFilt= er SolrDispatchFilter.init() done [junit4] 2> 922338 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.ZkController publishing state=3Ddown [junit4] 2> 922338 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.ZkController numShards not found on descriptor - reading it from system p= roperty [junit4] 2> 922339 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.ZkController Registering watch for external collection collection1 [junit4] 2> 922339 INFO (zkCallback-561-thread-1-processing-n:127.0.0= .1:40619_) [n:127.0.0.1:40619_ ] o.a.s.c.DistributedQueue NodeChildrenCh= anged fired on path /overseer/queue state SyncConnected [junit4] 2> 922339 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.c.ZkStateReader addZkWatch collection1 [junit4] 2> 922339 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.c.ZkStateReader Updating collection state at /collections/collection1/sta= te.json from ZooKeeper...=20 [junit4] 2> 922339 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.Overseer processM= essage: queueSize: 1, message =3D { [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:34315_", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:34315"} current state versi= on: 4 [junit4] 2> 922339 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.c.ZkStateReader Updating data for collection1 to ver 0=20 [junit4] 2> 922339 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.ZkController look for our core node name [junit4] 2> 922339 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.o.ReplicaMutator = Update state numShards=3D2 message=3D{ [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:34315_", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:34315"} [junit4] 2> 922340 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.o.ReplicaMutator = Collection already exists with numShards=3D2 [junit4] 2> 922340 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.o.ReplicaMutator = Assigning new node to shard shard=3Dshard1 [junit4] 2> 922441 INFO (OverseerStateUpdate-94032361050275843-127.0.= 0.1:40619_-n_0000000000) [n:127.0.0.1:40619_ ] o.a.s.c.o.ZkStateWriter g= oing to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 922442 INFO (zkCallback-567-thread-1-processing-n:127.0.0= .1:34315_) [n:127.0.0.1:34315_ ] o.a.s.c.c.ZkStateReader A cluster state= change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collec= tions/collection1/state.json for collection collection1 has occurred - upda= ting... (live nodes size: 2) [junit4] 2> 922442 INFO (zkCallback-567-thread-1-processing-n:127.0.0= .1:34315_) [n:127.0.0.1:34315_ ] o.a.s.c.c.ZkStateReader Updating data f= or collection1 to ver 1=20 [junit4] 2> 923340 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 923340 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.ZkController Check for collection zkNode:collection1 [junit4] 2> 923340 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.ZkController Collection zkNode exists [junit4] 2> 923340 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 923341 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.c.ZkStateReader path=3D/collections/collection1 configName=3Dconf1 specif= ied config exists in ZooKeeper [junit4] 2> 923341 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.SolrResourceLoader new SolrResourceLoader for directory: '/home/jenkins/w= orkspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/../../../../../= ../../../../home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-co= re/test/J1/temp/solr.search.mlt.CloudMLTQParserTest_B8961174A8583955-001/sh= ard-1-001/cores/collection1/' [junit4] 2> 923354 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.Config loaded config solrconfig.xml with version 0=20 [junit4] 2> 923360 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.SolrConfig current version of requestparams : -1 [junit4] 2> 923379 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.SolrConfig Using Lucene MatchVersion: 5.3.0 [junit4] 2> 923422 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 923423 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 923430 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.IndexSchema [collection1] Schema name=3Dtest [junit4] 2> 923684 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Initialized with rates=3Dopen-exchange-rates= .json, refreshInterval=3D1440. [junit4] 2> 923691 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.IndexSchema default search field in schema is text [junit4] 2> 923693 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.IndexSchema unique key field: id [junit4] 2> 923699 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 923702 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 923705 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-= rates.json [junit4] 2> 923706 WARN (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 923706 WARN (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 923706 INFO (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-= rates.json [junit4] 2> 923706 WARN (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 923706 WARN (coreLoadExecutor-2731-thread-1-processing-n:= 127.0.0.1:34315_) [n:127.0.0.1:34315_ c:collection1 x:collection1] o.a.s.= s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 923706 INFO (coreLoadExecutor-2731-thread-1-processing-n: [...truncated too long message...] rTest.test-seed#[B8961174A8583955]) [n:127.0.0.1:60088_ c:collection1 s:sha= rd2 r:core_node2 x:collection1] o.a.s.c.CachingDirectoryFactory Closing Moc= kDirectoryFactory - 2 directories currently being tracked [junit4] 2> 954057 INFO (TEST-CloudMLTQParserTest.test-seed#[B8961174= A8583955]) [n:127.0.0.1:60088_ c:collection1 s:shard2 r:core_node2 x:collec= tion1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/works= pace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.ml= t.CloudMLTQParserTest_B8961174A8583955-001/shard-2-002/cores/collection1/da= ta [CachedDir<>] [junit4] 2> 954058 INFO (TEST-CloudMLTQParserTest.test-seed#[B8961174= A8583955]) [n:127.0.0.1:60088_ c:collection1 s:shard2 r:core_node2 x:collec= tion1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/wor= kspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.= mlt.CloudMLTQParserTest_B8961174A8583955-001/shard-2-002/cores/collection1/= data [junit4] 2> 954058 INFO (TEST-CloudMLTQParserTest.test-seed#[B8961174= A8583955]) [n:127.0.0.1:60088_ c:collection1 s:shard2 r:core_node2 x:collec= tion1] o.a.s.c.CachingDirectoryFactory looking to close /home/jenkins/works= pace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.ml= t.CloudMLTQParserTest_B8961174A8583955-001/shard-2-002/cores/collection1/da= ta/index [CachedDir<>] [junit4] 2> 954058 INFO (TEST-CloudMLTQParserTest.test-seed#[B8961174= A8583955]) [n:127.0.0.1:60088_ c:collection1 s:shard2 r:core_node2 x:collec= tion1] o.a.s.c.CachingDirectoryFactory Closing directory: /home/jenkins/wor= kspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.= mlt.CloudMLTQParserTest_B8961174A8583955-001/shard-2-002/cores/collection1/= data/index [junit4] 2> 954058 INFO (zkCallback-591-thread-1-processing-n:127.0.0= .1:60088_) [n:127.0.0.1:60088_ ] o.a.s.c.c.ZkStateReader Updated cluster= state version to 12 [junit4] 2> 954058 INFO (TEST-CloudMLTQParserTest.test-seed#[B8961174= A8583955]) [n:127.0.0.1:60088_ c:collection1 s:shard2 r:core_node2 x:collec= tion1] o.a.s.c.Overseer Overseer (id=3D94032362672881672-127.0.0.1:60088_-n= _0000000002) closing [junit4] 2> 954058 INFO (OverseerStateUpdate-94032362672881672-127.0.= 0.1:60088_-n_0000000002) [n:127.0.0.1:60088_ ] o.a.s.c.Overseer Overseer= Loop exiting : 127.0.0.1:60088_ [junit4] 2> 954060 WARN (zkCallback-591-thread-1-processing-n:127.0.0= .1:60088_) [n:127.0.0.1:60088_ ] o.a.s.c.c.ZkStateReader ZooKeeper watch= triggered, but Solr cannot talk to ZK [junit4] 2> 954060 INFO (TEST-CloudMLTQParserTest.test-seed#[B8961174= A8583955]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@3ea46cc5{= HTTP/1.1}{127.0.0.1:0} [junit4] 2> 954060 INFO (TEST-CloudMLTQParserTest.test-seed#[B8961174= A8583955]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHa= ndler@35ad4c9e{/,null,UNAVAILABLE} [junit4] 2> 954061 INFO (TEST-CloudMLTQParserTest.test-seed#[B8961174= A8583955]) [n:127.0.0.1:60088_ c:collection1 s:shard2 r:core_node2 x:collec= tion1] o.a.s.c.ZkTestServer connecting to 127.0.0.1:47368 47368 [junit4] 2> 954164 INFO (Thread-2348) [n:127.0.0.1:34155_ c:collectio= n1 s:shard2 r:core_node4 x:collection1] o.a.s.c.ZkTestServer connecting to = 127.0.0.1:47368 47368 [junit4] 2> 954165 WARN (Thread-2348) [n:127.0.0.1:34155_ c:collectio= n1 s:shard2 r:core_node4 x:collection1] o.a.s.c.ZkTestServer Watch limit vi= olations:=20 [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2>=20 [junit4] 2> =094=09/solr/aliases.json [junit4] 2> =094=09/solr/clusterstate.json [junit4] 2> =093=09/solr/configs/conf1 [junit4] 2>=20 [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2>=20 [junit4] 2> =094=09/solr/live_nodes [junit4] 2> =093=09/solr/overseer/queue [junit4] 2> =093=09/solr/overseer/collection-queue-work [junit4] 2>=20 [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=3DCloudMLTQPars= erTest -Dtests.method=3Dtest -Dtests.seed=3DB8961174A8583955 -Dtests.multip= lier=3D3 -Dtests.slow=3Dtrue -Dtests.locale=3Des_CL -Dtests.timezone=3DAfri= ca/Kigali -Dtests.asserts=3Dtrue -Dtests.file.encoding=3DUTF-8 [junit4] ERROR 10.0s J1 | CloudMLTQParserTest.test <<< [junit4] > Throwable #1: java.lang.ClassCastException: java.lang.Stri= ng cannot be cast to java.util.ArrayList [junit4] > =09at __randomizedtesting.SeedInfo.seed([B8961174A8583955:= 30C22EAE06A454AD]:0) [junit4] > =09at org.apache.solr.search.mlt.CloudMLTQParserTest.test(= CloudMLTQParserTest.java:126) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase= .java:960) [junit4] > =09at org.apache.solr.BaseDistributedSearchTestCase$Shards= RepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) [junit4] > =09at java.lang.Thread.run(Thread.java:745) [junit4] 2> 954171 INFO (SUITE-CloudMLTQParserTest-seed#[B8961174A858= 3955]-worker) [n:127.0.0.1:60088_ c:collection1 s:shard2 r:core_node2 x:col= lection1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/wo= rkspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J1/temp/solr.search= .mlt.CloudMLTQParserTest_B8961174A8583955-001 [junit4] 2> NOTE: test params are: codec=3DAsserting(Lucene53): {range= _facet_l_dv=3DPostingsFormat(name=3DLuceneVarGapDocFreqInterval), multiDefa= ult=3DLucene50(blocksize=3D128), _version_=3DPostingsFormat(name=3DLuceneVa= rGapFixedInterval), lowerfilt=3DLucene50(blocksize=3D128), range_facet_i_dv= =3DPostingsFormat(name=3DLuceneVarGapFixedInterval), id=3DPostingsFormat(na= me=3DLuceneVarGapDocFreqInterval), lowerfilt1and2=3DLucene50(blocksize=3D12= 8), timestamp=3DPostingsFormat(name=3DLuceneVarGapFixedInterval), range_fac= et_l=3DPostingsFormat(name=3DLuceneVarGapFixedInterval), intDefault=3DPosti= ngsFormat(name=3DLuceneVarGapFixedInterval)}, docValues:{range_facet_l_dv= =3DDocValuesFormat(name=3DLucene50), range_facet_i_dv=3DDocValuesFormat(nam= e=3DMemory), timestamp=3DDocValuesFormat(name=3DMemory)}, sim=3DDefaultSimi= larity, locale=3Des_CL, timezone=3DAfrica/Kigali [junit4] 2> NOTE: Linux 3.13.0-54-generic amd64/Oracle Corporation 1.7= .0_80 (64-bit)/cpus=3D12,threads=3D1,free=3D224110080,total=3D518979584 [junit4] 2> NOTE: All tests run in this JVM: [ReturnFieldsTest, TestSo= rtingResponseWriter, CollectionReloadTest, JSONWriterTest, TestLMDirichletS= imilarityFactory, TestPivotHelperCode, LeaderFailoverAfterPartitionTest, Te= stManagedStopFilterFactory, SSLMigrationTest, SliceStateTest, TestPHPSerial= izedResponseWriter, CursorMarkTest, ResourceLoaderTest, TestClassNameShorte= ning, TestCoreDiscovery, TestWriterPerf, AnalysisAfterCoreReloadTest, Dista= nceFunctionTest, TestAtomicUpdateErrorCases, QueryResultKeyTest, ExitableDi= rectoryReaderTest, HdfsBasicDistributedZkTest, ScriptEngineTest, HdfsDirect= oryTest, SpellCheckComponentTest, TestStressVersions, IndexSchemaRuntimeFie= ldTest, TestDistributedGrouping, TestFieldCollectionResource, TestAnalyzedS= uggestions, CollectionsAPIDistributedZkTest, TestLMJelinekMercerSimilarityF= actory, BadComponentTest, TestAddFieldRealTimeGet, TestFreeTextSuggestions,= HighlighterMaxOffsetTest, TestUniqueKeyFieldResource, CacheHeaderTest, Tes= tRemoveLastDynamicCopyField, TestDownShardTolerantSearch, TestCoreContainer= , TestSolrDeletionPolicy2, BadCopyFieldTest, TestSearchPerf, LeaderInitiate= dRecoveryOnCommitTest, ZkControllerTest, SolrTestCaseJ4Test, TestReplicatio= nHandlerBackup, HdfsThreadLeakTest, LukeRequestHandlerTest, CopyFieldTest, = CursorPagingTest, TestAuthenticationFramework, MigrateRouteKeyTest, Analysi= sErrorHandlingTest, CloudExitableDirectoryReaderTest, TestFastWriter, TestD= istributedMissingSort, ShardSplitTest, TestExpandComponent, FieldMutatingUp= dateProcessorTest, TestSolrCoreProperties, TestSchemaResource, TestHashPart= itioner, ShardRoutingCustomTest, TestFileDictionaryLookup, TestFuzzyAnalyze= dSuggestions, UUIDUpdateProcessorFallbackTest, HdfsDirectoryFactoryTest, Te= stManagedResource, DistributedMLTComponentTest, SuggestComponentTest, Overs= eerRolesTest, HdfsRecoveryZkTest, TestNoOpRegenerator, CoreAdminHandlerTest= , PreAnalyzedFieldTest, DistribJoinFromCollectionTest, TestTolerantSearch, = CloudMLTQParserTest] [junit4] Completed [228/505] on J1 in 34.79s, 2 tests, 1 error <<< FAILU= RES! [...truncated 866 lines...] BUILD FAILED /home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:536: The following = error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:484: The following = error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following e= rror occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The fol= lowing error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:230: The follo= wing error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:512: Th= e following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1444:= The following error occurred while executing this line: /home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:999: = There were test failures: 505 suites, 2002 tests, 1 error, 59 ignored (25 a= ssumptions) Total time: 48 minutes 42 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any ------=_Part_90_2118563494.1434820695941 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_90_2118563494.1434820695941--