You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/01/09 07:38:00 UTC
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 1914
- Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1914/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.lucene.analysis.icu.TestICUNormalizer2CharFilter.testRandomStrings
Error Message:
startOffset 442 expected:<2698> but was:<2699>
Stack Trace:
java.lang.AssertionError: startOffset 442 expected:<2698> but was:<2699>
at __randomizedtesting.SeedInfo.seed([207270E5760248DA:A8FB705BD5061FEF]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:182)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:295)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:299)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:812)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:611)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:509)
at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:433)
at org.apache.lucene.analysis.icu.TestICUNormalizer2CharFilter.testRandomStrings(TestICUNormalizer2CharFilter.java:189)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 2816 lines...]
[junit4] Suite: org.apache.lucene.analysis.icu.TestICUNormalizer2CharFilter
[junit4] 2> TEST FAIL: useCharFilter=false text='kses '\u034e \u1b19\u1b21\u1b53 ywpamesm szpdduy \u26bc\u2652\u26f9\u266d\u26c6\u2610\u2660 \u07ea\u2703 \u1751\u174d\u1746\u1756\u175f \ua5ae\ua550\ua54b xotpw \ua8b9\ua8b3\ua88d\ua884\ua8a8 shd oz.{0,5 qq qppmznq \ufe946\uc1e0\ufcca\udbd4\udf19\u1178\u001b\u001b\ufe39\uff1a etyfav \ua29f tbbzgi \u0012`\u01b0 \u2d09\u2d2f\u2d03\u2d1e\u2d2d \u319d\u319c\u319b\u319f\u3195\u3193 \u2dc3\u2d93\u2dd6\u2daf ghtltn \u15e2\u157c\u1641\u154d\u14d8\u1637\u1485 pgew fhyoz e{ \u0012\uf3b0-_ }\uf31c-\u02ce\ud9a8\udcc4 fzgia kchvyarzb \u297f\u292c\u295e\u2938 \n<p> \uaa65\uaa79\uaa7f\uaa61\uaa69\uaa7c \u46fcl\u8c91\\ { \u1e1d\u1e16 \ud800\ude85\ud800\ude84 bgxpp e \u18ee\u18db\u18e0 \ue5428\ud9f7\udfca\u0093K </p \ud800\ude96\ud800\ude9f qbrqfga \u2d93\u2db2\u2d9f\u2da8 \ud966\udca0 lB \u3012 \u0c58\u0c33\u0c7c\u0c64 lntyvrck tyghsyskmky \u1751\u1758\u1753\u1746\u1740 hgfyr zpo \u25fc\u25e4\u25bd\u25ac\u25a5\u25dd\u25e2\u25b3 gQ sjuefrpmglvny </ q gbwtwp agiglfm ? \u515b\ue9e3\uf551' fs ezze \udbf4\udf29\udbf1\udcd2\udbc3\udd30\udbf5\udf5a cfzfp {0, wjb \u29ed\u2991\u29ac\u29ff\u29a8\u298a |[u czgb prixux \"?>\"</ ?l.ijx.sj \u0bc1\u0b97\u0bcc\u0bf1 \udb3b\udc1e\ua152 \ufcd1<\u0363\u0388\u0638 \ud80c\udead af ftsrdn bpnaumvz nhqloy zbisx \ua05e\ua3da d fll snwdcickcfan rqubbd \n&#x wxme lsuhh dflowz olarfg \u1383\u1393\u1382\u1381\u1397 \u198e\u1991 jkbe ]q[{1,5 lthm \u209a\u209f\u208f\u2091\u209d\u2098\u209c \uda6b\udf0b\u00f9\ud8db\udd59 pzskhrtfz s hll \ud808\udd12 ainos yufnl lzdcf wqiyf xug \ucfb2\uc15b\uc357 <!-- zwh \ud8ac\ude5e(\u0481 \u03b6\u73243\u27d6\u3ead\u8e94\ufced \ufff0\ufff3\ufff4\ufffb\ufff0\ufff2\ufff1 rnknw \ueb03\u0432\ud824\udd21\ue7ce\ue1c1 ?nnal qwiwj \uf7b2\u01be\uda7e\ude78\u5812 vise me(|[)d{1 jt \ufe56\ufe62\ufe69\ufe5b\ufe57\ufe66\ufe5d\ufe51\ufe6e r &q</s kd \u6bc3\uf5f4\uebf3\ud8fa\udc5b\u0011 \u195d\u195b dqer mymxv n \u6d77\uc73e\u32c3\ue403\u2c39\u008c \u0756\u0765\u0758\u077d\u0751\u0768 gjqcp \ud803\udc11 vtwivgb \u00b4\u04b0\uf573\ube1e ammlepnsyvay \uef4a/ \u1071\u109c\u1063\u104d\u108b xusujupp jbqi \ue3c2\ud984\udd86\u5a7a\u07ab\uf512 7\uf0ce\uaafc\u20ae\u2f42\uea2c\ufba5\u001f vyr \u177e\u1766\u177e\u1763\u1775 dboano ecch pdme awqsbltdw upijdzj mbYDo r? \ud800\uddcc\ud800\uddce\ud800\udda0\ud800\uddce ogshuyutiejqeq svrOnEljMvx airhAuCli baukwzi (h.)])]{ &#x\n<p>\" yfbqsgx mmgrusb X6\u02db \ud845\udc9d xyuq ybgcri \u07fc xerfekjfoy pxljxi qbovujr nhgo kiwzta ribtoq xqth \u2abb\u2aef\u2a48\u2aca\u2a79 \u0c3d\u0c4c \ud802\udd2b xuejnb sb |p] \u0451\ufafbq-e\ud9b3\udc17 j.[.|[l \ud804\udcb6\ud804\udccb\ud804\udc92 qbm rbkh \ue25b\ue0a2\uf7bb\u001d\u1b65\u05ac \u2de6\u2de7\u2de0\u2dff\u2df8\u2de3\u2df9 \u3022\u301b\u300f\u3002\u3015\u3023 { dg cmhfue gunhq \u2430 injgdtcla \udb3c\ude93\u75bf\udab7\udf9e ; hba < rlqcdma u{ ]-k \u2105\u2124\u212b\u2109 kbrlys \u0745\udbdb\udf93; \u2106\u2109\u2144\u2141\u2130\u2124\u213a\u2137 \uaadb\uaab3\uaac7\uaaaf\uaaa0\uaa96\uaabd\uaa90\uaabe \u20e1\u20d1\u20e9\u20f4\u20f9 jyxqjygn \ub28a\u77d68\u00fb\uaad2\uda95\udfd2\ueb72\u20b1 \ud800\udf83\ud800\udf90 '</sc ({1 \u137e6\u0450\ud923\udfac\uda34\udfcb\ud807\ude42 oofycdfe l{0,5}bm( eufja E\u19d5\ued54V\ueed0\ue6f3 z\u001c\ue29d\ueb19 \ufe6a\ufe5a\ufe5e\ufe6b\ufe51 \\\"\"&#x \u0694J efaagkco \n</script> \u138f\u1387\u139b\u1380\u139e\u1395\u1382 \u1cf4\u1cdf\u1ce2\u1cfd\u1ce2\u1cd1\u1cf9\u1cd5 \u0012\u1b99\ue327r <br\\\"\\'< \u171d\u1718\u1716\u1704 \u177d\u176f\u1776\u1777 mseku \ud83c\udc1e \u5153\ufaff\u0318 \udb4c\ude4c\u001a`\u0013 \u00ec\u00fa\u008c\u00b3\u00a4\u00ec\u00f9\u00c7\u00f0 \ufe67\ufe62\ufe6e cguytjbbn \u769c\ueb66G\uda79\udec4 gsnuhm \uc1f6J\udb08\udee3[\u0005\u0587 \ud178\u0019 \u19bc\u19c6 xtrh \u0a38\u0a38\u0a79\u0a0a\u0a59\u0a6d cp \ueeb2\ue8c7[0\uf126 \u0137\u0105\u0139\u011c\u0100\u017e \u0002\ue157T\ud977\ude97 \u0e14\u0e73\u0e53\u0e03\u0e14 gsrp \ud834\ude21\ud834\ude22\ud834\ude16\ud834\ude21 \u200a\u2005\u2062\u2054\u204b\u2033 \u0e35\u0e73 ddi \ud802\ude66\ud802\ude68\ud802\ude79\ud802\ude77\ud802\ude73 \ud808\ude43\ud808\udd4f qygosusxs \u21be\u21c9\u21f7\u21ed\u21ed\u21ac\u21b9 \ud8d9\udd53\u2153\ud9f2\udd96\u02d7 v tddtl \ud800\udf48 &;< s eabsmai oo spct miza \u0127\u0135\u014f\u012d l]|st{1 qeuuicszt ?]ydw| ehcc azxqta \u0cca\u0cae\u0cd7\u0ced\u0ce4 \u251f\u2534\u2576\u2507 uv wst ffmhfwy \u3758\uf8be\ucdc0\u8520 zBRyt \u0676\u06a7\u03c9 \ud9d0\ude1b\u0691\u03d4 ohjn <!--< \ub6a6\ua5da\u01bb\ue291v\udb8d\udc5a flp \u2c65\u2c71\u2c7f\u2c67\u2c66\u2c69\u2c6b\u2c73\u2c79 </ n) \u7ba8\ud834\udecb imrwn acdmbbsxm hbfabyh pVus \u0534!\u0015\u1523\u0701 cdrjub \udb9d\udf0a\u015c\u022b\ud629\u380e \u2488\u24d6\u24a7\u24cc\u2460\u2483 \ue7800 \ud834\udcba\ud834\udced\ud834\udccc \ud965\udc2c\u01f1\u008c\u0431\u001f\u077e \ufafa\uf941\ufa4f\uf921\uf921\uf990\uf9b4\uf912 </p><sc \u20ae\u20c3 y{0 irkwhminu duvqlv wyripr gwxltq \u13d5\u13b2\u13d5\u13a8 mj \uf43f\ud88a\udc0c vfgdesny \u05a9\u05b0\u05d6\u05a8\u05dc \ud834\udce1\ud834\udc6e\ud834\udc52 tmgldvu vnzybdj xiuafzyh zrw \uf9d2\ue216\u0549\uffcf\u8bfd </sc \ud9e7\udcbe\ue889'\ud85d\udc9f \u04f2\u04e0\ud97d\udcff\u052c utwlup sig wxjrw \u16c2\u16dd \ua701\ua70b\ua717 qel & qvuwoyb qrsdjsnz thriob bbdgwywx \u041fiy\u3416b knlz c\ue950G\u037bh\u17bd\uda74\udedb fkibkam \u07bcU\u0de6\u25e8\u04b8\u0004\uf34c qre y(|{0,5}z rdjbe \u2c79\u2c6d\u2c7c\u2c70\u2c72\u2c63\u2c70\u2c6a vj gwpswnet \ua3b7=\ua207\u0601\uda20\udfef\uea5c\u0013\u07c8\ufad5\uf4b0\u03ad \ud834\udf4c\ud834\udf2f widcpztys \ufe43\ufe46\ufe3b\ufe3d\ufe42\ufe39\ufe36 oqvsao XuFA hrcj \u07dc\u07fc\u07f5\u07dc ve T\ue3db\u4aeb\u06ea\u9336{ ccwqv \u1974\u1954\u1956\u197b\u1971\u197d\u1962 \ufe57\ufe50\ufe59\ufe5d\ufe5c\ufe5d\ufe59\ufe67\ufe57\ufe5c \u175d\u175e\u1743\u1749\u1752\u1746\u1747 ((.z)[ mcbip ybuj oekucidfj fcsebz bimhqy \ua9d5\ua9be\ua9df\ua998\ua9c4 \u2c23\u2c11\u2c2b\u2c39\u2c4d\u2c06\u2c06 gq \u0d11\u0d45\u0d12\u0d46\u0d02 \uc3a6\u02cd\u0017\u0ebcI p{1,5 kgypv \u03b0\u0c3e lnowhf pl fqk ..)b?|j)({ \uf973\ufa18\uf939\uf933 N|\ub22d\udbb3\udf42 jgw \u041e\u04fb\u04e3\u04a4 nbc \u174a\u175e\u174e\u1754\u1753\u1742\u174c\u1759\u1749 \u066e\u0692\u0628\u06d9\u063d \u1d3e\u1d3e\u1d7c\u1d26 !\u04f6! dl \u0629\u001d qk drt 2\uf8c3 \ua71f\ua71c\ua71c\ua71f\ua70e \u24e1\u2489\u24d3\u2484 <!- ztqmy g iovisha \ud834\udc2e\ud834\udcbf '?></ \ua92b\ua908\ua91d\ua900\ua90e\ua91f\ua920 [| \ue6eb\ueeab <!- \ud834\udcff\ud834\udc66 lokhcx \ue85f]\u30b4\u0646r\u001f lwl w\u0286\ud877\udef7\u540c\ubc08 \ud834\udf5d\ud834\udf52 \udb63\udce1\u018e\u000f avkgbwulf \u1a80\u1a61\u1a4b\u1a51\u1a51 \udbed\udf72\udb8a\udcc2 wawsy hpr \u1f46\u1f94\u1ff5 psrotnd pl \uaad3\uaada \" pwseccp \ue204\u32f5\u0773@ \ub5a9\uf8ac\ud9a6\ude25v\ued56\u056f hq ghk \ua87c\ua85e\ua848\ua868\ua86b\ua87c \ued31\udab0\ude18r\ud8cc\udd21\ue5204\uf687\u013f </s \u0af7\u0aa2\u0abc\u0a82\u0ada\u0af2\u0ac5\u0ac0\u0ae9\u0af9 \u1a30\u1aae\u1aa0\u1a97\u1a58\u1a26\u1a63\u1a63\u1aa4\u1a5d \ueb56\uff59D\u328e\u0004\\?G\u0500\u0010 jjn \u5166\u7a98\ud964\udc1a \u2afe\u2a58\u2a8c\u2ab5\u2afd\u2a4f rdym motkk \u0344w\u02af \ud7d3\ud7d0\ud7d1\ud7ee jbjju I\uf991d \u20d6\u20f9\u20fa\u20ec vblgg viyfsclnd ignjlve \u25b4\u25bb\u25cd\u25d0\u25b6\u25be\u25c4 jbuuq <?>?>;;</ e zdaflc ww{1,5}?j) jefvtxno \ue6cf`\ue053v\u0144* \u207d\u2076\u2089\u2092\u2078\u209f\u207e\u2081 \ud92f\ude06\u2501*\u0154\ubc7d hwunmxz s FptG \u2c98 \u68d1;\uf71dh\ud970\ude23C\u0527 qebl q \u2add\u2a9f\u2ab4\u2a40\u2ae4\u2ac6\u2aa4\u2ac5\u2a39 nafyxh m <!-- \ud802\udf67\ud802\udf64\ud802\udf61 \u0751\u077c\u0774\u0769\u0776\u0761\u0750 \u175e\u1753\u175f hf -ch.])luq{0 \ua66a eevuc \u0eea\u0ec5\u0eb0\u0eda\u0e93\u0eae\u0ed7 8\u6601\ud943\udfb9\uedb0\u035d plufxcjgwsqj \u1712\u1714\u170f\u1710 rzqvx .| \u0194\u0556W\ud855\udd8c\u001c\ue9d0\u001a v \u46f2\ue828\uf66d\u6f8a\uc8ab\ufaf6\uebd0\ue41c \udb9c\ude21\udaa1\udf6dK\u62ef}@ \ud834\udf7b\ud834\udf74\ud834\udf64\ud834\udf64\ud834\udf6b \ud808\udc75\ud808\uddcd lzy eizpGEf cwrcynrpoqm qwgbaky p{0 \ua70c\ua703\ua704\ua71e vqik oi hgbv \u27eb\u27e7\u27e0\u27e3 xltmakf lqm \u2c70\u2c66\u2c66\u2c6f\u2c7e p?){1,5}(. \\\"< pe dsihckz ahhbxnl fubap \ud83c\udc26\ud83c\udc08\ud83c\udc07 pwiyfelvxxxiqm \u19e6\u19fc\u19f3\u19e1 imjg qch \u243f\u2409\u241d\u2430\u2409\u2439\u240d\u2415\u2437\u2414 \uabca\uabfc\uabc4\uabe3\uabd6 lh itsnvsyxpk \ud9d7\udf98\u06b8\u0458\u0366 gplpcv agq a \u067a\u06d6\u06e7\u0673\u0628\u0659\u06e5\u063e rhsdt \ufe53\ufe5b\ufe52\ufe59\ufe65\ufe5d \u8b96\udb94\udd28H >x\u0098\uf98b &sovt \u00db\u00dc\ud8c7\ude24 '86508 H\ucde6D xvf \u186b\n \ueb55e\u06df\uf275 hj \u1b90\u1ba8 yeafj f mqrcrymu \u0267\u9f93 X\ufdb4\u53f3P hajpsgx vy \ufe15\ufe1f\ufe14\ufe1a \u21c6\u219b\u21db\u2195\u219d\u2197\u21d2\u21fe\u21eb +\u0797\ufd74\u1670G rjs aWqzym ystizll \ua7af\ua7c2\ua797\ua7be\ua788\ua744\ua7a8\ua7e7 \ufe27\ufe2a\ufe27\ufe25\ufe22\ufe2b\ufe27\ufe2a edcztb \u20aa\u20cc\u20c3\u20b1\u20a4\u20ac \u1896\u18ab\u18ae \ud97b\uddcc\u07b5\uf9b2\u07df\u1207\u29af okbp \u6daa\ue626\ue550 \u0108\u0582\ud8e5\udd65 nrmu ?>\\\"< :\u6d9d\u0720\u044f\uec57\ufed2\udb41\udf62\u0003 \ud800\udf1b\ud800\udf23\ud800\udf26\ud800\udf14\ud800\udf08 \u4dd8\u4dc4\u4df5\u4dcd\u4dee\u4de4\u4deb\u4dca \u02b2\u02bf\u02c4\u02c3 919841 usfehkny \uff50 hnesg fdq qngepxjqg \u00e5r\ub9a2\u00fb\u3d42\uf33e \ud801\udc98\ud801\udca2\ud801\udc8d \ua979\ua960\ua96e\ua972\ua974 \u2711\u274f\u2782\u27b6\u276c\u273d \ud87a\udd77\ud963\udcdd\u0e74\u00059 UXmPl '&;100 xno \u1b49\u1b1a\u1b1c\u1b52 g \ud802\udd13 yhqq dbwe a \uaddb-(\uda02\udf89\uf995 wsu \ud800\uddf6\ud800\uddf3\ud800\udde3 ([ \ufb7c\ufd58\ufb76\ufc3e\ufcd9\ufd26\ufc5e\ufd18\ufc5f\ufc9f fuc jtfgytkj \uda3b\udfe1<\u12d4\ue17aH\uee96\uf280\u55ce dthgii oksfj \ud835\ude92\ud835\uddb9\ud835\uddbc \u29fc\u2990\u299d\u29eb\u29df\u29a7\u2988\u29f2 \udbe6\udc1a\udbc2\udf59\udbd3\udc08 \ua9a4\u2077\uf36f fkvisw \uff07\udabb\udf8d,\u0224\udb54\ude55\u0012\u020f rbecm Tnm \u2ffa\u2ff7\u2ff3\u2ff2\u2ffb\u2ff0\u2ff0\u2ffc \" fkcutmxbf djpkfuezgb \u0d4e\u0d4e\u0d5e\u0d0e\u0d12\u0d7e \ue2ac\u00e0\u00e4x /\ub189\ua8fd cshx \u073e\u0727\u0740 xwjcq \u2c64 ;<?< \ud829\udd79\ud9d8\udec7 qsxvw \\ aqmy \ud806\ude338T rfmwx rgn ]eqx|) liyqkl \u00a5\u00bd\u00e6\u0085\u00d4\u00f5\u0088\u00a0\u0091\u00de eylnjj \u2386\u23eb\u234e\u2324\u235d\u23b3\u230a qtgwd zpxlaee hacb aigilvta \u1789\u17e1\u17fe\u17e5\u1793\u1780\u17d7\u17e7\u17c2 zull \u1764\u1778 .oc[{1,5} wbfy xjmallb \u19c4\u19ce\u19cf\u19b5 \u591a\u0002\ue714c\u032d lfurgkt \u022c\u001f\u0674\ueb6e \ud801\udc97\ud801\udc8b\ud801\udc83 kvpgpdo \u0561\u327b!\ufc78\uda33\udc5f\ud6c1 dvqt k e 1445 \u2503\u2560\u2555\u257f\u2537\u2506 \ua540\ua5f3\ua612 \u0bc1\u0bc1\u0bdd\u0bda\u0bd1\u0ba7 \u0d3d\u0d50\u0d11\u0d1c\u0d49\u0d74 qsv dmod \u02af\ufac1\uf970\u75c3\ue5ba\u0006f\udad3\udeec\u952e\uebef \u001d \u0c73\u0c69\u0c7d\u0c79\u0c7e \u34b4\u02a2\u7a96\ue1b9\u0003* Zuqrmdr \u0544\u0539 \u242c\u2427\u2405 a i \ud801\udc74 \u1756\u1751\u175b\u175d\u1742\u1751\u1748 \u13cc\u13c1 \ud800\udfa2\ud800\udfd4\ud800\udfdc anbplh rlwasjmma agonin am bxqewbrtf \u1978\u1964\u1952\u1967\u1958 \u0671\u0655'\u2d53\u071d \ua8aa\ua8b8\ua8a5\ua8d3\ua8b2 qitnam przyb \u2527 f qemn \u073f\u0701 \udab0\udc05Z\u0316\udb2b\udfc4\ufcd4\ubb28 \ud802\udc25 \u0785\ud84d\udcde\uf274\u0459\u5c61/o ciibafl \u022c\uf00b\uc95c\ue5db\r\u07cd drgnwu \u3101\u310f\u311c\u311d\u310b\u312c\u3116 vy \ue620\u0018 \u2581\u2590\u2584\u259f\u2583\u258e\u2593 dtz fmrxynp qynrzz \u009a\u008f\u00e1\u00ad\u0088\u0080 \udb8a\udf11;\ud943\udeed kalvf vdghq vdde .yj({ ?></ cksspunc \u0c07\u0c3e\u0c05\u0c37\u0c2e\u0c0e \ua922\ua91e\ua912\ua907\ua919\ua92b\ua910 utelngpxq \u23d2\u2360\u2324\u230f \uc993\uc008\uc795\uc3e5\ubc17 \udb40\udc62\udb40\udc49 e(l-x]w lj kbb \ud9bf\ude2d \u2fc6\u2f05 nuthtje \u5610\u62e2\u7ee9\u67f5\u969e\u897e\u8630 \ud829\udf51\"\u3643\u0487\u209c\u03d3\u0091 fiiyk \u1213\u12f3\u1270\u1324\u1374 sejpevzlwqs tlubgybmp mky aScyNgR \u0d28\u0d52\u0d5e bmrwvyag \ubca6\u9f88;\ud824\uded7 lmja teqe |m{1,5}| lgtm qpvcxtx ufqwopjiwbo \ud8ca\udcf6\u7019. fogwyrj ylmuhvyd }7\ubc61\ufff8\u07c0\u7c12 ijsju fobg G\ud866\ude93\u04e2* \u0164 rlojy nsiffrfzk \ud83c\udd60\ud83c\uddec nxkdfnfyhte zgsvvrn cdz vdwelq \u64e3'\u23b6\u5e36\uf854\u570aj\udafb\ude63 \u177a\u177c\u1772 drayhtpq eiqgqviy {0, wlkk wrnrlzsc x ehukojszw oyxaaziz \u0003\uc86a\u40b7 \u02d3\uf30a\u0160\u138c\ud879\udfd0\u06a4 \ud803\ude6a\ud803\ude6e\ud803\ude71\ud803\ude7a kperrz djfeibpd hrwtpz \ud544\u2160\u61a4> <s jvlpxxx \u03b9\u05c8\uda7c\ude6a\uf297\ueda9 bjdckk .? sch tept \u0585 SwKr runouee \ub798i\ud848\ude8a\u41ed\u02b0 m t \ua87f\ua862\ua843\ua869\ua879 miwjrrzsb \u2e4c\u2e74 slxzkjm \u106b\u100b xzpwexy 7 \ua955\u0730\u0015\u6fb9 \u10ae u xr]( ;\u8fcb\ufc2d \uecbf\uecf4C\ue3b8D xcp \ud834\udc13\ud834\udcc6\ud834\udcf9 onzrlbazdytzt bkrm e \uda37\udf71\uef32\ue2fc\u0011 abexma \u0017\u5458\uf6b3\uf0d9 \u02b7\u8424\uf8e3\u05c0\uf9c0\u0585 \u03cb{\u021b0\uf2ca \ud801\udc91\ud801\udc9a\ud801\udc88\ud801\udc86\ud801\udc9b hl)- \u19f3\u19f2\u19e4\u19eb juxps \ud86c\ude9f\ud86b\udc51 \u27f5\u27f6\u27f6 nba \u09bd\u09a6\u09f1\u09f4\u0996\u09e8\u0986 < \uf09f\u6be5\u1691 tYUqupX <?</s bunwk wkd \u6abd\ud94f\ude55\ue067\uf0ec.\uf328 quxrw \u987f\u832f\uefccT\uda58\udedc \u1323\u1234\u133c\u1201\u1321\u1321 ;;\"< amoyeqaef <!-- cbcpakoqdo jo \uf87a\u04b9\u0002O eaoocyxo dlqmns 7 rkagprsd wfhkd \udb40\udc4d\udb40\udc40\udb40\udc5a qsyfeg oh KRMn \u1a7b\u1a52 \u1e94\u1e55\u1efb\u1e49\u1e6b\u1e77 \u303c\u301c\u3029\u301c uqxohfzkc \uda7d\udf30\ueedf bdkyxbti bs)f kqrjk !\u001b\u05d7\ud9ed\udf4c eofprd \u98c4\u000f\uf482 \u31f7\u31fc\u31f1\u31f2 \ud83c\ude6a \u057c\u0544 \ua8eb\ua8f2 \ufe33\ufe30 ?]--q|] eagdaf mtevog gyzdyrnux vyshrhsw \u072c\uea84\u704b\u6a57\u013d\u0018 `\u00d7\u03ef\ubc68\u5c8f\u0a47 eqi \udbfc\udf83\udbdf\udc88\udbed\udd1a r \u026b\ue342\uebf0 bxrl {0,5 qfndc ck.l{0 rge \u2a62\u2a25 ffcgg h -->; \u0220] <scrip ypc mwhvi \u27d0\u27c3\u27e5\u27ed\u27e2\u27ec\u27e3\u27cb\u27df\u27cb pc ilm \ufe3e\ufe38\ufe42\ufe3b\ufe4d\ufe41 mhg xut \u9a6d{\u056e aly \ua720\ua77e\ua7a6\ua751\ua750 \udbcc\udf3d\udbe9\uddaf\udbf1\udca7\udbcb\udfa5\udbc2\udee5 \udbc0\udcaf\ue11a\uda4a\udc2f\u07d3\u62f2 yhwtcabhosm ijngm xpcho wrwoqb xupljcn '- \ufe68\ud95f\udc89 ige seyz gmcn tze czyrd \u0e1e\u0e17\u0e38\u0e49\u0e6f q\u1a21\ud8e2\udeec\uf426\uf8c6\u02f3F\ud940\udeb8\uebbf\u001a hv \ua9a8\ua9da\ua9ac\ua9d6\ua98d\ua989\ua99e\ua9d4 zkkyoaj opac \u1ea3\u1e02\u1e07\u1e59\u1e47\u1e75 \ud800\udf4b\ud800\udf34 \uaa44\uaa52\uaa11\uaa1c\uaa50\uaa46 ayeg luvdoxpx rqgfny \u2bb5\u2b24\u2b9b\u2b89\u2b14\u2b70\u2b49\u2b53 \u3197\u3192\u319e arhzqpykn fquc \u5022 swjr \u21bf\u2190\u21c4\u21b5\u21c5\u2197\u21cc\u21a0\u21c0 zezc mchskxix szmk \u10ca\u10aa\u10c4\u10b5\u10d9\u10e2 yfe rab|t.k[.[ \u862b\uabd8\uf4d9\ud883\ude56L ryvtweajvx \u30de\u30f7\u30c3\u30c3\u30b3\u30c7\u30b1 zzo ulktj y ePr \u1c27\u1c08\u1c3e\u1c0c\u1c1f\u1c19\u1c44\u1c3d \ud8e3\udef6A \ud834\udc5f\ud834\udcfe\ud834\udca7 \u081a\u083b unn qxp \ue362\ueb00\u94d7 \u2ff2\u2ff5\u2ffe\u2ffb\u2ffb\u2ffe\u2ff9 |{1,5 \ud834\ude1e\ud834\ude1b \ue480\u0009\u79f2\u04b3\u000c\uf44b\u0011\u013a qj t \u25c9\u25b9\u25fa \u9e23\uea65\u606d \u192a\u1929 v.p]i?| < >&# qr ixc xuprqn M nkpqnzg # \u000e\ue14a \u0265\ueffb\uf55a\u0351\u83f0\u65c8\u10f2 \udacf\udf99\uda98\udca6\u5860; \uf6c6\uf420\u68a2\u001b hvy \ud800\udf4a\ud800\udf47\ud800\udf38 \u2d7d\u2d3e\u2d5e\u2d71 \u2900\u2928\u2931\u291a\u295a\u2972\u2945\u2935 \u16af\u16e5\u16ef\u16f5\u16b8 hda&#x \ud860\udc7c\ud867\udca0\ud840\udf13\ud846\udee0 soi buvv ujyfyh slhesfw x \u24df\uef50\u0014d\ue922\udb64\udf76\u392b \u174c w \ud9b5\udd9aT\u07a0\u0334 pfbcaua gjqvl \u0011\ue2ee\udbbb\ude8c\u5add{\u9bc5\ue4ba\u4b08 \uf41b\ufc10<\u8682,\u0594 \"; vyureez arnzddkejkr \u0779\u077c\u077c \u1c57\u1c75\u1c52\u1c64\u1c5e\u1c5f\u1c57 gbzb \u0652\u06f8\u068c <s rzew uhjblbdf \u2362\ue915\u07c0\ud888\udcaf\ue34b fd wfrhckw \u0803\u083d\u081c\u081c \uf743\uc697w\u0173\ud980\udf49 \ub9e6\ueae1) ?u--(b(o{ vvbacflxon vfhqsc nermldrk \ud802\udf5f xlf .v{0 s \u21ec\u21dd\u21eb \u5fe6\ua4f07 eqghfdgsa \u2753\u2715\u2720\u277b wpdxdsyqt \ufc1d\ue779 \ufe0d\ufe03\ufe0e\ufe0a\ufe0e <scr \ud800\udf43\ud800\udf36 f { \n120 kbgt htubogu 2'\uec81\uda6c\uddde ui '&#xln
[junit4] 2> wz \u2690\u2631\u2683\u2683\u2654\u26e1 \ua90e\ua92a\ua924\ua903\ua913 xbzhbhf zw \u11be\u11b5\u1136 \u6f91\u0411\u076c\ufaf9\ue9e3 kw \uebde\u03d0V\udb45\ude58\u0376\u001f '
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestICUNormalizer2CharFilter -Dtests.method=testRandomStrings -Dtests.seed=207270E5760248DA -Dtests.slow=true -Dtests.locale=vi -Dtests.timezone=Africa/Timbuktu -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 0.93s | TestICUNormalizer2CharFilter.testRandomStrings <<<
[junit4] > Throwable #1: java.lang.AssertionError: startOffset 442 expected:<2698> but was:<2699>
[junit4] > at __randomizedtesting.SeedInfo.seed([207270E5760248DA:A8FB705BD5061FEF]:0)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:182)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:295)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:299)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:812)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:611)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:509)
[junit4] > at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:433)
[junit4] > at org.apache.lucene.analysis.icu.TestICUNormalizer2CharFilter.testRandomStrings(TestICUNormalizer2CharFilter.java:189)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=1243, maxDocsPerChunk=566, blockSize=9), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=1243, blockSize=9)), sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=vi, timezone=Africa/Timbuktu
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=2,threads=1,free=20236344,total=46002176
[junit4] 2> NOTE: All tests run in this JVM: [TestICUNormalizer2CharFilterFactory, TestICUTokenizerCJK, TestICUCollationKeyAnalyzer, TestCharArrayIterator, TestICUTokenizerFactory, TestICUNormalizer2CharFilter]
[junit4] Completed in 2.84s, 17 tests, 1 failure <<< FAILURES!
[...truncated 36 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/build.xml:456: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:2069: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/analysis/build.xml:106: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/analysis/build.xml:38: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/module-build.xml:58: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1348: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:956: There were test failures: 15 suites, 113 tests, 1 failure
Total time: 21 minutes 33 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-MacOSX (64bit/jdk1.8.0) - Build # 1915
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1915/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.testDistribSearch
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([3473D19A11DC16EE]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ChaosMonkeySafeLeaderTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([3473D19A11DC16EE]:0)
Build Log:
[...truncated 8848 lines...]
[junit4] Suite: org.apache.solr.cloud.ChaosMonkeySafeLeaderTest
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/init-core-data-001
[junit4] 2> 1584864 T5014 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1584865 T5014 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /xb/s
[junit4] 2> 1584878 T5014 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> 1584880 T5014 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1584881 T5015 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1584887 T5014 oasc.ZkTestServer.run start zk server on port:50987
[junit4] 2> 1584889 T5014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1584898 T5014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1584907 T5022 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@67f3fdd3 name:ZooKeeperConnection Watcher:127.0.0.1:50987 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1584907 T5014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1584908 T5014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1584908 T5014 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1584919 T5014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1584923 T5014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1584941 T5025 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7c470aeb name:ZooKeeperConnection Watcher:127.0.0.1:50987/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1584951 T5014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1584953 T5014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1584953 T5014 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1585021 T5014 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1585082 T5014 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1585128 T5014 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1585178 T5014 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1585179 T5014 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1585205 T5014 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 1585205 T5014 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1585218 T5014 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1585218 T5014 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1585226 T5014 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1585227 T5014 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1585233 T5014 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1585235 T5014 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1585241 T5014 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1585242 T5014 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1585247 T5014 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1585248 T5014 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1585253 T5014 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1585254 T5014 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1585259 T5014 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1585259 T5014 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1585267 T5014 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1585268 T5014 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1585277 T5014 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1585278 T5014 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1587902 T5014 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 1587908 T5014 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@7bd92aa1{/xb/s,null,AVAILABLE}
[junit4] 2> 1587911 T5014 oejs.AbstractConnector.doStart Started ServerConnector@6deb9257{HTTP/1.1}{127.0.0.1:50990}
[junit4] 2> 1587911 T5014 oejs.Server.doStart Started @1591518ms
[junit4] 2> 1587912 T5014 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 1587914 T5014 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1587914 T5014 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-002
[junit4] 2> 1587915 T5014 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-002/'
[junit4] 2> 1587985 T5014 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-002/solr.xml
[junit4] 2> 1588031 T5014 oasc.CoreContainer.<init> New CoreContainer 1651111639
[junit4] 2> 1588031 T5014 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-002/]
[junit4] 2> 1588032 T5014 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1588033 T5014 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1588033 T5014 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1588034 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1588034 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1588035 T5014 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1588035 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1588036 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1588036 T5014 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1588037 T5014 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1588038 T5014 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1588038 T5014 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1588039 T5014 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1588039 T5014 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1588039 T5014 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50987/solr
[junit4] 2> 1588040 T5014 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1588040 T5014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1588041 T5014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1588045 T5039 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4351d10d name:ZooKeeperConnection Watcher:127.0.0.1:50987 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1588046 T5014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1588046 T5014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1588052 T5014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1588058 T5042 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@77963292 name:ZooKeeperConnection Watcher:127.0.0.1:50987/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1588058 T5014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1588062 T5014 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1588072 T5014 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1588088 T5014 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 1588100 T5014 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 1588112 T5014 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 1588124 T5014 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1588130 T5014 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50990_xb%2Fs
[junit4] 2> 1588136 T5014 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50990_xb%2Fs
[junit4] 2> 1588153 T5014 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 1588161 T5014 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1588168 T5014 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1588177 T5014 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:50990_xb%2Fs
[junit4] 2> 1588178 T5014 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1588184 T5014 oasc.Overseer.start Overseer (id=93114273118486531-127.0.0.1:50990_xb%2Fs-n_0000000000) starting
[junit4] 2> 1588196 T5014 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1588233 T5014 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1588235 T5044 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1588238 T5014 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1588249 T5014 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1588255 T5014 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1588275 T5043 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1588294 T5046 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 1588295 T5046 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1588297 T5042 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1588298 T5046 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1588310 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50990/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50990_xb%2Fs",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1588316 T5043 oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50990/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50990_xb%2Fs",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1588316 T5043 oasco.ClusterStateMutator.createCollection building a new cName: control_collection
[junit4] 2> 1588316 T5043 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1588322 T5042 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1589300 T5046 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1589301 T5046 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 1589303 T5046 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1589303 T5046 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1589306 T5046 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1589306 T5046 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-002/collection1/'
[junit4] 2> 1589308 T5046 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest%203473D19A11DC16EE-001/tempDir-002/collection1/lib/.svn/' to classloader
[junit4] 2> 1589309 T5046 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest%203473D19A11DC16EE-001/tempDir-002/collection1/lib/classes/' to classloader
[junit4] 2> 1589309 T5046 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest%203473D19A11DC16EE-001/tempDir-002/collection1/lib/README' to classloader
[junit4] 2> 1589347 T5046 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 1589354 T5046 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 1589371 T5046 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1589384 T5046 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1589424 T5046 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1589428 T5046 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1589438 T5046 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1589722 T5046 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1589723 T5046 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1589724 T5046 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1589732 T5046 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1589812 T5046 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 1589813 T5046 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 1589814 T5046 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/control/data/
[junit4] 2> 1589815 T5046 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@64121252
[junit4] 2> 1589827 T5046 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/control/data
[junit4] 2> 1589828 T5046 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/control/data/index/
[junit4] 2> 1589828 T5046 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/control/data/index' doesn't exist. Creating new index...
[junit4] 2> 1589829 T5046 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/control/data/index
[junit4] 2> 1589829 T5046 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=65.1669921875, floorSegmentMB=1.484375, forceMergeDeletesPctAllowed=9.34931997542342, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1589833 T5046 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 1589833 T5046 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1589840 T5046 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1589841 T5046 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1589843 T5046 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1589843 T5046 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1589844 T5046 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1589844 T5046 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1589846 T5046 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1589847 T5046 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1589848 T5046 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1589848 T5046 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1589849 T5046 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1589849 T5046 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1589850 T5046 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1589850 T5046 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1589851 T5046 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1589852 T5046 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1589852 T5046 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1589853 T5046 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1589858 T5046 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1589859 T5046 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1589860 T5046 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1589860 T5046 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1589861 T5046 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1589862 T5046 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1589863 T5046 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1589863 T5046 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1589864 T5046 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1589877 T5046 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1589880 T5046 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1589883 T5046 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1589885 T5046 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1589886 T5046 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1589890 T5046 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1589891 T5046 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1589891 T5046 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=29, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7026552942239119]
[junit4] 2> 1589895 T5046 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/control/data/index,segFN=segments_1,generation=1}
[junit4] 2> 1589896 T5046 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1589897 T5046 oass.SolrIndexSearcher.<init> Opening Searcher@662f26e5[collection1] main
[junit4] 2> 1589897 T5046 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 1589901 T5046 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1589901 T5046 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1589903 T5046 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1589903 T5046 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1589911 T5046 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1589913 T5046 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1589913 T5046 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1589913 T5046 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1589914 T5046 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1589918 T5047 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@662f26e5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1589918 T5046 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1589920 T5050 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50990/xb/s collection:control_collection shard:shard1
[junit4] 2> 1589923 T5014 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1589923 T5014 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1589924 T5014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1589928 T5014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1589933 T5050 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1589934 T5053 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@37d014d7 name:ZooKeeperConnection Watcher:127.0.0.1:50987/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1589934 T5014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1589936 T5014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1589939 T5014 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1589951 T5014 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1589960 T5050 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1589966 T5042 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1589967 T5050 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1589968 T5050 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C5387 name=collection1 org.apache.solr.core.SolrCore@55f5b1e0 url=http://127.0.0.1:50990/xb/s/collection1 node=127.0.0.1:50990_xb%2Fs C5387_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:50990/xb/s, node_name=127.0.0.1:50990_xb%2Fs, state=down}
[junit4] 2> 1589968 T5050 C5387 P50990 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50990/xb/s/collection1/
[junit4] 2> 1589969 T5050 C5387 P50990 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1589969 T5050 C5387 P50990 oasc.SyncStrategy.syncToMe http://127.0.0.1:50990/xb/s/collection1/ has no replicas
[junit4] 2> 1589970 T5050 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50990/xb/s/collection1/ shard1
[junit4] 2> 1589970 T5050 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1589974 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 1589983 T5053 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1589985 T5042 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1590021 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:50990/xb/s",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 1590030 T5054 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1590034 T5053 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1590072 T5050 oasc.ZkController.register We are http://127.0.0.1:50990/xb/s/collection1/ and leader is http://127.0.0.1:50990/xb/s/collection1/
[junit4] 2> 1590072 T5050 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50990/xb/s
[junit4] 2> 1590073 T5050 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1590073 T5050 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 1590073 T5050 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1590098 T5042 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1590124 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50990/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50990_xb%2Fs",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 1590129 T5043 oasco.ReplicaMutator.updateState Update state numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50990/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50990_xb%2Fs",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1590248 T5042 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1590251 T5053 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1592400 T5014 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 1592402 T5014 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 1592412 T5014 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@18ba1d95{/xb/s,null,AVAILABLE}
[junit4] 2> 1592414 T5014 oejs.AbstractConnector.doStart Started ServerConnector@5608c4ac{HTTP/1.1}{127.0.0.1:50994}
[junit4] 2> 1592415 T5014 oejs.Server.doStart Started @1596022ms
[junit4] 2> 1592416 T5014 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 1592417 T5014 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1592418 T5014 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-003
[junit4] 2> 1592418 T5014 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-003/'
[junit4] 2> 1592490 T5014 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-003/solr.xml
[junit4] 2> 1592543 T5014 oasc.CoreContainer.<init> New CoreContainer 1778292871
[junit4] 2> 1592544 T5014 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-003/]
[junit4] 2> 1592544 T5014 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1592544 T5014 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1592544 T5014 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1592545 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1592545 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1592546 T5014 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1592546 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1592546 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1592547 T5014 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1592547 T5014 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1592548 T5014 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1592549 T5014 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1592549 T5014 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1592549 T5014 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1592550 T5014 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50987/solr
[junit4] 2> 1592550 T5014 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1592551 T5014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1592553 T5014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1592563 T5068 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@115cc2e name:ZooKeeperConnection Watcher:127.0.0.1:50987 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1592563 T5014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1592564 T5014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1592570 T5014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1592574 T5071 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d3219a6 name:ZooKeeperConnection Watcher:127.0.0.1:50987/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1592574 T5014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1592592 T5014 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1593612 T5014 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50994_xb%2Fs
[junit4] 2> 1593614 T5014 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50994_xb%2Fs
[junit4] 2> 1593633 T5014 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1593648 T5072 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 1593649 T5072 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1593651 T5072 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1593651 T5042 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1593657 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50994/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50994_xb%2Fs",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 1593659 T5043 oasco.ReplicaMutator.updateState Update state numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50994/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50994_xb%2Fs",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1593659 T5043 oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 1593660 T5043 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2
[junit4] 2> 1593666 T5042 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 1593667 T5053 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 1593666 T5071 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 1594655 T5072 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1594656 T5072 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1594657 T5072 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1594657 T5072 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1594659 T5072 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1594660 T5072 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-003/collection1/'
[junit4] 2> 1594662 T5072 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest%203473D19A11DC16EE-001/tempDir-003/collection1/lib/.svn/' to classloader
[junit4] 2> 1594663 T5072 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest%203473D19A11DC16EE-001/tempDir-003/collection1/lib/classes/' to classloader
[junit4] 2> 1594666 T5072 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest%203473D19A11DC16EE-001/tempDir-003/collection1/lib/README' to classloader
[junit4] 2> 1594755 T5072 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 1594762 T5072 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 1594802 T5072 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1594821 T5072 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1594876 T5072 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1594882 T5072 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1594891 T5072 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1595135 T5072 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1595143 T5072 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1595144 T5072 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1595150 T5072 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1595227 T5072 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1595227 T5072 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 1595228 T5072 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty1/
[junit4] 2> 1595228 T5072 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@64121252
[junit4] 2> 1595230 T5072 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty1
[junit4] 2> 1595232 T5072 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty1/index/
[junit4] 2> 1595233 T5072 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty1/index' doesn't exist. Creating new index...
[junit4] 2> 1595234 T5072 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty1/index
[junit4] 2> 1595234 T5072 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=65.1669921875, floorSegmentMB=1.484375, forceMergeDeletesPctAllowed=9.34931997542342, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1595241 T5072 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 1595242 T5072 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1595250 T5072 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1595253 T5072 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1595254 T5072 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1595254 T5072 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1595254 T5072 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1595255 T5072 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1595255 T5072 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1595256 T5072 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1595256 T5072 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1595258 T5072 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1595258 T5072 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1595260 T5072 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1595261 T5072 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1595262 T5072 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1595263 T5072 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1595268 T5072 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1595269 T5072 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1595271 T5072 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1595275 T5072 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1595276 T5072 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1595277 T5072 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1595279 T5072 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1595279 T5072 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1595280 T5072 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1595282 T5072 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1595285 T5072 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1595286 T5072 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1595297 T5072 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1595299 T5072 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1595300 T5072 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1595303 T5072 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1595312 T5072 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1595315 T5072 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1595315 T5072 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1595318 T5072 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=29, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7026552942239119]
[junit4] 2> 1595321 T5072 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty1/index,segFN=segments_1,generation=1}
[junit4] 2> 1595321 T5072 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1595322 T5072 oass.SolrIndexSearcher.<init> Opening Searcher@120dd600[collection1] main
[junit4] 2> 1595322 T5072 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1595331 T5072 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1595332 T5072 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1595336 T5072 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1595337 T5072 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1595337 T5072 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1595339 T5072 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1595340 T5072 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1595340 T5072 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1595341 T5072 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1595342 T5073 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@120dd600[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1595343 T5072 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1595353 T5076 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50994/xb/s collection:collection1 shard:shard2
[junit4] 2> 1595360 T5014 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1595360 T5014 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1595366 T5076 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 1595415 T5076 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 1595424 T5076 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1595424 T5076 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C5388 name=collection1 org.apache.solr.core.SolrCore@37bb7b39 url=http://127.0.0.1:50994/xb/s/collection1 node=127.0.0.1:50994_xb%2Fs C5388_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:50994/xb/s, node_name=127.0.0.1:50994_xb%2Fs, state=down}
[junit4] 2> 1595425 T5076 C5388 P50994 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50994/xb/s/collection1/
[junit4] 2> 1595425 T5076 C5388 P50994 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1595425 T5076 C5388 P50994 oasc.SyncStrategy.syncToMe http://127.0.0.1:50994/xb/s/collection1/ has no replicas
[junit4] 2> 1595426 T5076 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50994/xb/s/collection1/ shard2
[junit4] 2> 1595426 T5076 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 1595428 T5042 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1595431 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 1595434 T5042 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 1595440 T5071 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 1595443 T5053 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 1595461 T5054 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1595466 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:50994/xb/s",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 6
[junit4] 2> 1595587 T5071 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 1595588 T5053 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 1595621 T5076 oasc.ZkController.register We are http://127.0.0.1:50994/xb/s/collection1/ and leader is http://127.0.0.1:50994/xb/s/collection1/
[junit4] 2> 1595622 T5076 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50994/xb/s
[junit4] 2> 1595622 T5076 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1595622 T5076 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 1595623 T5076 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1595631 T5042 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1595636 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50994/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50994_xb%2Fs",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 7
[junit4] 2> 1595637 T5043 oasco.ReplicaMutator.updateState Update state numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50994/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50994_xb%2Fs",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1595746 T5071 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 1595750 T5053 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 1595751 T5042 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 1597609 T5014 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 1597614 T5014 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 1597620 T5014 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@65ac394e{/xb/s,null,AVAILABLE}
[junit4] 2> 1597623 T5014 oejs.AbstractConnector.doStart Started ServerConnector@294d23d3{HTTP/1.1}{127.0.0.1:50997}
[junit4] 2> 1597624 T5014 oejs.Server.doStart Started @1601230ms
[junit4] 2> 1597624 T5014 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c
[junit4] 2> 1597626 T5014 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1597626 T5014 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-004
[junit4] 2> 1597626 T5014 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-004/'
[junit4] 2> 1597717 T5014 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-004/solr.xml
[junit4] 2> 1597765 T5014 oasc.CoreContainer.<init> New CoreContainer 1811365546
[junit4] 2> 1597766 T5014 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-004/]
[junit4] 2> 1597767 T5014 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 1597768 T5014 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 1597768 T5014 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1597769 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1597770 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 1597770 T5014 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1597771 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1597771 T5014 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1597771 T5014 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1597772 T5014 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1597772 T5014 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false
[junit4] 2> 1597773 T5014 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1597774 T5014 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1597774 T5014 oasc.CoreContainer.load Host Name: 127.0.0.1
[junit4] 2> 1597774 T5014 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:50987/solr
[junit4] 2> 1597775 T5014 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1597775 T5014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1597779 T5014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1597784 T5090 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@721e0e03 name:ZooKeeperConnection Watcher:127.0.0.1:50987 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1597785 T5014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1597785 T5014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1597791 T5014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1597796 T5093 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5a853704 name:ZooKeeperConnection Watcher:127.0.0.1:50987/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1597797 T5014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1597819 T5014 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1598834 T5014 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50997_xb%2Fs
[junit4] 2> 1598837 T5014 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:50997_xb%2Fs
[junit4] 2> 1598853 T5014 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 1598866 T5094 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 1598866 T5094 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1598868 T5094 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1598870 T5042 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1598874 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50997/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50997_xb%2Fs",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 8
[junit4] 2> 1598876 T5043 oasco.ReplicaMutator.updateState Update state numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50997/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50997_xb%2Fs",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1598876 T5043 oasco.ReplicaMutator.updateState Collection already exists with numShards=3
[junit4] 2> 1598877 T5043 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard3
[junit4] 2> 1598882 T5042 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1598886 T5071 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1598886 T5053 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1598888 T5093 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1599871 T5094 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1599871 T5094 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1599873 T5094 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1599873 T5094 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1599876 T5094 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1599877 T5094 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-004/collection1/'
[junit4] 2> 1599880 T5094 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest%203473D19A11DC16EE-001/tempDir-004/collection1/lib/.svn/' to classloader
[junit4] 2> 1599882 T5094 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest%203473D19A11DC16EE-001/tempDir-004/collection1/lib/classes/' to classloader
[junit4] 2> 1599882 T5094 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest%203473D19A11DC16EE-001/tempDir-004/collection1/lib/README' to classloader
[junit4] 2> 1599952 T5094 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 1599960 T5094 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 1599976 T5094 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 1599992 T5094 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1600030 T5094 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1600038 T5094 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1600045 T5094 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1600295 T5094 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1600295 T5094 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1600297 T5094 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1600304 T5094 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1600383 T5094 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 1600384 T5094 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 1600384 T5094 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty2/
[junit4] 2> 1600384 T5094 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@64121252
[junit4] 2> 1600386 T5094 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty2
[junit4] 2> 1600387 T5094 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty2/index/
[junit4] 2> 1600387 T5094 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty2/index' doesn't exist. Creating new index...
[junit4] 2> 1600389 T5094 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty2/index
[junit4] 2> 1600408 T5094 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=65.1669921875, floorSegmentMB=1.484375, forceMergeDeletesPctAllowed=9.34931997542342, segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1600417 T5094 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 1600418 T5094 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1600425 T5094 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1600426 T5094 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1600427 T5094 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1600427 T5094 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1600427 T5094 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1600428 T5094 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1600428 T5094 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1600428 T5094 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1600437 T5094 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1600438 T5094 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1600438 T5094 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1600439 T5094 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1600439 T5094 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 1600440 T5094 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 1600441 T5094 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 1600444 T5094 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 1600445 T5094 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 1600448 T5094 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 1600449 T5094 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 1600450 T5094 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 1600450 T5094 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 1600451 T5094 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 1600451 T5094 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 1600452 T5094 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 1600452 T5094 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 1600453 T5094 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1600453 T5094 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 1600464 T5094 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1600466 T5094 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1600468 T5094 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1600469 T5094 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1600470 T5094 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1600473 T5094 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1600473 T5094 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1600475 T5094 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=29, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7026552942239119]
[junit4] 2> 1600477 T5094 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.ChaosMonkeySafeLeaderTest 3473D19A11DC16EE-001/tempDir-001/jetty2/index,segFN=segments_1,generation=1}
[junit4] 2> 1600477 T5094 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1600477 T5094 oass.SolrIndexSearcher.<init> Opening Searcher@26ee7a09[collection1] main
[junit4] 2> 1600478 T5094 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1600483 T5094 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1600483 T5094 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1600485 T5094 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1600485 T5094 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 1600485 T5094 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1600487 T5094 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1600487 T5094 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1600487 T5094 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 1600488 T5094 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1600489 T5095 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@26ee7a09[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1600490 T5094 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1600492 T5098 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:50997/xb/s collection:collection1 shard:shard3
[junit4] 2> 1600493 T5014 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0
[junit4] 2> 1600494 T5014 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1600495 T5098 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election
[junit4] 2> 1600515 T5098 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3
[junit4] 2> 1600523 T5042 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1600527 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"collection1"} current state version: 9
[junit4] 2> 1600533 T5071 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1600534 T5053 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1600537 T5042 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1600545 T5098 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1600546 T5098 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C5389 name=collection1 org.apache.solr.core.SolrCore@4b737043 url=http://127.0.0.1:50997/xb/s/collection1 node=127.0.0.1:50997_xb%2Fs C5389_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:50997/xb/s, node_name=127.0.0.1:50997_xb%2Fs, state=down}
[junit4] 2> 1600546 T5098 C5389 P50997 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:50997/xb/s/collection1/
[junit4] 2> 1600546 T5098 C5389 P50997 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 1600547 T5098 C5389 P50997 oasc.SyncStrategy.syncToMe http://127.0.0.1:50997/xb/s/collection1/ has no replicas
[junit4] 2> 1600547 T5098 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:50997/xb/s/collection1/ shard3
[junit4] 2> 1600548 T5098 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3
[junit4] 2> 1600551 T5093 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1600574 T5054 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1600578 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://127.0.0.1:50997/xb/s",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 10
[junit4] 2> 1600696 T5093 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1600700 T5071 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1600711 T5053 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1600734 T5098 oasc.ZkController.register We are http://127.0.0.1:50997/xb/s/collection1/ and leader is http://127.0.0.1:50997/xb/s/collection1/
[junit4] 2> 1600735 T5098 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:50997/xb/s
[junit4] 2> 1600735 T5098 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 1600736 T5098 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 1600736 T5098 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1600753 T5042 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 1600760 T5043 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50997/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50997_xb%2Fs",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 11
[junit4] 2> 1600763 T5043 oasco.ReplicaMutator.updateState Update state numShards=3 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:50997/xb/s",
[junit4] 2> "node_name":"127.0.0.1:50997_xb%2Fs",
[junit4] 2> "numShards":"3",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard3",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1600879 T5042 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1600881 T5071 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1600881 T5093 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1600883 T5053 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 1603047 T5014 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 3
[junit4] 2> 1603050 T5014 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 1603057 T5014 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@72195e2d{/xb/s,null,AVAILABLE}
[junit4] 2> 1603060 T5014 oejs.AbstractConnector.doStart Started ServerConnector@43ff678b{HTTP/1.1}{127.0.0.1:51000}
[junit4] 2> 1603061 T5014 oejs.Server.doStart Started @1606667ms
[junit4] 2> 1603061 T5014 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.L
[...truncated too long message...]
at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:340)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.destroy(SolrDispatchFilter.java:194)
[junit4] 2> at org.apache.solr.cloud.ChaosMonkey.stopJettySolrRunner(ChaosMonkey.java:197)
[junit4] 2> at org.apache.solr.cloud.ChaosMonkey.stop(ChaosMonkey.java:550)
[junit4] 2> at org.apache.solr.cloud.AbstractFullDistribZkTestBase.destroyServers(AbstractFullDistribZkTestBase.java:1572)
[junit4] 2> at org.apache.solr.BaseDistributedSearchTestCase.tearDown(BaseDistributedSearchTestCase.java:283)
[junit4] 2> at org.apache.solr.cloud.AbstractDistribZkTestBase.tearDown(AbstractDistribZkTestBase.java:231)
[junit4] 2> at org.apache.solr.cloud.AbstractFullDistribZkTestBase.tearDown(AbstractFullDistribZkTestBase.java:1546)
[junit4] 2> at org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.tearDown(ChaosMonkeySafeLeaderTest.java:86)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:483)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:885)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 21) Thread[id=5140, name=Thread-1127, state=WAITING, group=TGRP-ChaosMonkeySafeLeaderTest]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at org.apache.solr.core.CloserThread.run(CoreContainer.java:933)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {rnd_b=Lucene50(blocksize=128), _version_=PostingsFormat(name=Memory doPackFST= true), a_t=PostingsFormat(name=Memory doPackFST= true), a_i=Lucene50(blocksize=128), id=Lucene50(blocksize=128)}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=ar_BH, timezone=CST
[junit4] 2> NOTE: Mac OS X 10.8.5 x86_64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=2,threads=37,free=28774408,total=244977664
[junit4] 2> NOTE: All tests run in this JVM: [TestRangeQuery, ZkStateWriterTest, AssignTest, AnalysisErrorHandlingTest, TestQuerySenderNoQuery, TestBadConfig, TestCloudPivotFacet, TestPseudoReturnFields, TestNRTOpen, ZkSolrClientTest, OpenCloseCoreStressTest, TestDynamicLoading, FullSolrCloudDistribCmdsTest, StressHdfsTest, ShowFileRequestHandlerTest, RAMDirectoryFactoryTest, AsyncMigrateRouteKeyTest, TestClassNameShortening, TestRemoteStreaming, AnalyticsMergeStrategyTest, BasicDistributedZkTest, TestRandomFaceting, SolrIndexSplitterTest, OverseerTest, DocValuesMultiTest, HighlighterMaxOffsetTest, TestValueSourceCache, ConvertedLegacyTest, TestUtils, CSVRequestHandlerTest, TestSolrDynamicMBean, ChangedSchemaMergeTest, TestCollationFieldDocValues, CopyFieldTest, TestPartialUpdateDeduplication, TestStressLucene, TestSolrCoreProperties, TestPhraseSuggestions, AnalyticsQueryTest, TestLRUStatsCache, TestBM25SimilarityFactory, DistributedFacetPivotWhiteBoxTest, XmlUpdateRequestHandlerTest, TestCursorMarkWithoutUniqueKey, TestComponentsName, SolrInfoMBeanTest, TestCollationField, TermsComponentTest, SuggesterWFSTTest, TestReload, RegexBoostProcessorTest, TestCloudManagedSchemaConcurrent, MoreLikeThisHandlerTest, CollectionsAPIAsyncDistributedZkTest, TestStressReorder, TestCopyFieldCollectionResource, TestBlobHandler, TestArbitraryIndexDir, TestPerFieldSimilarity, TestSolrQueryParser, TestAddFieldRealTimeGet, DistanceFunctionTest, TestPostingsSolrHighlighter, ChaosMonkeySafeLeaderTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ChaosMonkeySafeLeaderTest -Dtests.seed=3473D19A11DC16EE -Dtests.slow=true -Dtests.locale=ar_BH -Dtests.timezone=CST -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s | ChaosMonkeySafeLeaderTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([3473D19A11DC16EE]:0)
[junit4] Completed in 7224.59s, 1 test, 2 errors <<< FAILURES!
[...truncated 1198 lines...]
BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:187: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1348: The following error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:956: There were test failures: 460 suites, 1868 tests, 1 suite-level error, 1 error, 1580 ignored (18 assumptions)
Total time: 185 minutes 40 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any