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 2013/08/09 15:34:54 UTC
[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.8.0-ea-b99) - Build #
3063 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3063/
Java: 64bit/jdk1.8.0-ea-b99 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.client.solrj.embedded.JettyWebappTest.testAdminUI
Error Message:
Server returned HTTP response code: 500 for URL: http://127.0.0.1:51479/test/
Stack Trace:
java.io.IOException: Server returned HTTP response code: 500 for URL: http://127.0.0.1:51479/test/
at __randomizedtesting.SeedInfo.seed([E1EC7E8DE0CF3F60:D93E9DDE87A4A4DF]:0)
at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1781)
at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1382)
at java.net.URL.openStream(URL.java:1038)
at org.apache.solr.client.solrj.embedded.JettyWebappTest.testAdminUI(JettyWebappTest.java:98)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 10900 lines...]
[junit4] Suite: org.apache.solr.client.solrj.embedded.JettyWebappTest
[junit4] 2> 183932 T569 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 184169 T569 oejw.StandardDescriptorProcessor.visitServlet NO JSP Support for /test, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 184215 T569 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 184222 T569 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 184222 T569 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr
[junit4] 2> 184222 T569 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\'
[junit4] 2> 184263 T569 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\solr.xml
[junit4] 2> 184276 T569 oasc.ConfigSolrXml.<init> Config-defined core root directory:
[junit4] 2> 184276 T569 oasc.CoreContainer.<init> New CoreContainer 1715760334
[junit4] 2> 184276 T569 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\]
[junit4] 2> 184277 T569 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 184277 T569 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 184278 T569 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 184278 T569 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 184278 T569 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 184278 T569 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 184278 T569 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 184278 T569 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 184278 T569 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 184279 T569 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 184287 T569 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr
[junit4] 2> 184305 T569 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\
[junit4] 2> 184306 T569 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 184308 T571 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1
[junit4] 2> 184308 T571 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\'
[junit4] 2> 184344 T571 oasc.SolrConfig.initLibs Adding specified lib dirs to ClassLoader
[junit4] 2> 184345 T571 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\extraction\lib).
[junit4] 2> 184345 T571 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4] 2> 184346 T571 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\clustering\lib).
[junit4] 2> 184346 T571 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4] 2> 184347 T571 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\langid\lib).
[junit4] 2> 184347 T571 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4] 2> 184348 T571 oasc.SolrResourceLoader.addToClassLoader WARN No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\velocity\lib).
[junit4] 2> 184348 T571 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4] 2> 184369 T571 oasu.SolrIndexConfig.<init> IndexWriter infoStream solr logging is enabled
[junit4] 2> 184370 T571 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 184396 T571 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 184397 T571 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 184410 T571 oass.IndexSchema.readSchema [collection1] Schema name=example
[junit4] 2> 184605 T571 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 184619 T571 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 184623 T571 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 184642 T571 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 184642 T571 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.embedded.JettyWebappTest-1376055245019\
[junit4] 2> 184643 T571 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@10180a78
[junit4] 2> 184643 T571 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 184643 T571 oasc.SolrCore.initListeners [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4] 2> 184643 T571 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.embedded.JettyWebappTest-1376055245019\
[junit4] 2> 184643 T571 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.embedded.JettyWebappTest-1376055245019\index/
[junit4] 2> 184643 T571 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.embedded.JettyWebappTest-1376055245019\index' doesn't exist. Creating new index...
[junit4] 2> 184644 T571 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.embedded.JettyWebappTest-1376055245019\index
[junit4] 2> 184646 T571 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-302-thread-1]: init: current segments file is "null"; deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@4bd68b38
[junit4] 2> 184647 T571 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-302-thread-1]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 184647 T571 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-302-thread-1]: 0 msec to checkpoint
[junit4] 2> 184647 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: init: create=true
[junit4] 2> 184647 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]:
[junit4] 2> dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6bc54822 lockFactory=org.apache.lucene.store.NativeFSLockFactory@66fd967d)
[junit4] 2> index=
[junit4] 2> version=4.5-SNAPSHOT
[junit4] 2> matchVersion=LUCENE_45
[junit4] 2> analyzer=null
[junit4] 2> ramBufferSizeMB=100.0
[junit4] 2> maxBufferedDocs=-1
[junit4] 2> maxBufferedDeleteTerms=-1
[junit4] 2> mergedSegmentWarmer=null
[junit4] 2> readerTermsIndexDivisor=1
[junit4] 2> termIndexInterval=32
[junit4] 2> delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
[junit4] 2> commit=null
[junit4] 2> openMode=CREATE
[junit4] 2> similarity=org.apache.lucene.search.similarities.DefaultSimilarity
[junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=1, maxMergeCount=2, mergeThreadPriority=-1
[junit4] 2> default WRITE_LOCK_TIMEOUT=1000
[junit4] 2> writeLockTimeout=1000
[junit4] 2> codec=Lucene42
[junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream
[junit4] 2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0, forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> indexerThreadPool=org.apache.lucene.index.ThreadAffinityDocumentsWriterThreadPool@5b07834
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2>
[junit4] 2> 184648 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: now flush at close waitForMerges=true
[junit4] 2> 184648 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: start flush: applyAllDeletes=true
[junit4] 2> 184648 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: index before flush
[junit4] 2> 184648 T571 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-302-thread-1]: coreLoadExecutor-302-thread-1 startFullFlush
[junit4] 2> 184648 T571 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-302-thread-1]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 184648 T571 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-302-thread-1]: coreLoadExecutor-302-thread-1 finishFullFlush success=true
[junit4] 2> 184648 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: apply all deletes during flush
[junit4] 2> 184648 T571 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-302-thread-1]: prune sis=org.apache.lucene.index.SegmentInfos@ff45881 minGen=9223372036854775807 packetCount=0
[junit4] 2> 184648 T571 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-302-thread-1]: now merge
[junit4] 2> 184648 T571 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-302-thread-1]: index:
[junit4] 2> 184648 T571 oasu.LoggingInfoStream.message [CMS][coreLoadExecutor-302-thread-1]: no more merges pending; now return
[junit4] 2> 184649 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: waitForMerges
[junit4] 2> 184649 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: waitForMerges done
[junit4] 2> 184649 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: now call final commit()
[junit4] 2> 184649 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: commit: start
[junit4] 2> 184649 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: commit: enter lock
[junit4] 2> 184649 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: commit: now prepare
[junit4] 2> 184649 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: prepareCommit: flush
[junit4] 2> 184650 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: index before flush
[junit4] 2> 184650 T571 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-302-thread-1]: coreLoadExecutor-302-thread-1 startFullFlush
[junit4] 2> 184650 T571 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-302-thread-1]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 184650 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: apply all deletes during flush
[junit4] 2> 184650 T571 oasu.LoggingInfoStream.message [BD][coreLoadExecutor-302-thread-1]: prune sis=org.apache.lucene.index.SegmentInfos@ff45881 minGen=9223372036854775807 packetCount=0
[junit4] 2> 184651 T571 oasu.LoggingInfoStream.message [DW][coreLoadExecutor-302-thread-1]: coreLoadExecutor-302-thread-1 finishFullFlush success=true
[junit4] 2> 184651 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: startCommit(): start
[junit4] 2> 184651 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: startCommit index= changeCount=1
[junit4] 2> 184652 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: done all syncs: []
[junit4] 2> 184652 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: commit: pendingCommit != null
[junit4] 2> 184652 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: commit: wrote segments file "segments_1"
[junit4] 2> 184653 T571 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-302-thread-1]: now checkpoint "" [0 segments ; isCommit = true]
[junit4] 2> 184653 T571 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6bc54822 lockFactory=org.apache.lucene.store.NativeFSLockFactory@66fd967d),segFN=segments_1,generation=1}
[junit4] 2> 184653 T571 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 184653 T571 oasu.LoggingInfoStream.message [IFD][coreLoadExecutor-302-thread-1]: 1 msec to checkpoint
[junit4] 2> 184653 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: commit: done
[junit4] 2> 184653 T571 oasu.LoggingInfoStream.message [IW][coreLoadExecutor-302-thread-1]: at close:
[junit4] 2> 184654 T571 oasc.SolrCore.initWriters created json: solr.JSONResponseWriter
[junit4] 2> 184655 T571 oasc.SolrCore.initWriters adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 2> 184655 T571 oasc.SolrCore.initWriters created velocity: solr.VelocityResponseWriter
[junit4] 2> 184655 T571 oasc.SolrCore.initWriters created xslt: solr.XSLTResponseWriter
[junit4] 2> 184655 T571 oasr.XSLTResponseWriter.init xsltCacheLifetimeSeconds=5
[junit4] 2> 184706 T571 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@303f44bb
[junit4] 2> 184708 T571 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 184708 T571 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 184709 T571 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 184709 T571 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 184709 T571 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.embedded.JettyWebappTest-1376055245019\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.embedded.JettyWebappTest-1376055245019\;done=false>>]
[junit4] 2> 184709 T571 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.embedded.JettyWebappTest-1376055245019\
[junit4] 2> 184709 T571 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.embedded.JettyWebappTest-1376055245019\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.embedded.JettyWebappTest-1376055245019\index;done=false>>]
[junit4] 2> 184709 T571 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\org.apache.solr.client.solrj.embedded.JettyWebappTest-1376055245019\index
[junit4] 2> 184709 T571 oasc.CoreContainer.recordAndThrow ERROR Unable to create core: collection1 org.apache.solr.common.SolrException: Error loading class 'solr.clustering.ClusteringComponent'
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:831)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:624)
[junit4] 2> at org.apache.solr.core.CoreContainer.createFromLocal(CoreContainer.java:518)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:553)
[junit4] 2> at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:245)
[junit4] 2> at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:237)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error loading class 'solr.clustering.ClusteringComponent'
[junit4] 2> at org.apache.solr.core.SolrResourceLoader.findClass(SolrResourceLoader.java:449)
[junit4] 2> at org.apache.solr.core.SolrResourceLoader.findClass(SolrResourceLoader.java:381)
[junit4] 2> at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:525)
[junit4] 2> at org.apache.solr.core.SolrCore.createInitInstance(SolrCore.java:581)
[junit4] 2> at org.apache.solr.core.SolrCore.initPlugins(SolrCore.java:2114)
[junit4] 2> at org.apache.solr.core.SolrCore.initPlugins(SolrCore.java:2108)
[junit4] 2> at org.apache.solr.core.SolrCore.initPlugins(SolrCore.java:2141)
[junit4] 2> at org.apache.solr.core.SolrCore.loadSearchComponents(SolrCore.java:1172)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:762)
[junit4] 2> ... 11 more
[junit4] 2> Caused by: java.lang.ClassNotFoundException: solr.clustering.ClusteringComponent
[junit4] 2> at java.net.URLClassLoader$1.run(URLClassLoader.java:365)
[junit4] 2> at java.net.URLClassLoader$1.run(URLClassLoader.java:354)
[junit4] 2> at java.security.AccessController.doPrivileged(Native Method)
[junit4] 2> at java.net.URLClassLoader.findClass(URLClassLoader.java:353)
[junit4] 2> at java.lang.ClassLoader.loadClass(ClassLoader.java:423)
[junit4] 2> at java.net.FactoryURLClassLoader.loadClass(URLClassLoader.java:788)
[junit4] 2> at java.lang.ClassLoader.loadClass(ClassLoader.java:356)
[junit4] 2> at java.lang.Class.forName0(Native Method)
[junit4] 2> at java.lang.Class.forName(Class.java:339)
[junit4] 2> at org.apache.solr.core.SolrResourceLoader.findClass(SolrResourceLoader.java:433)
[junit4] 2> ... 19 more
[junit4] 2>
[junit4] 2> 184710 T571 oasc.SolrException.log ERROR null:org.apache.solr.common.SolrException: Unable to create core: collection1
[junit4] 2> at org.apache.solr.core.CoreContainer.recordAndThrow(CoreContainer.java:930)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:562)
[junit4] 2> at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:245)
[junit4] 2> at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:237)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error loading class 'solr.clustering.ClusteringComponent'
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:831)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:624)
[junit4] 2> at org.apache.solr.core.CoreContainer.createFromLocal(CoreContainer.java:518)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:553)
[junit4] 2> ... 8 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error loading class 'solr.clustering.ClusteringComponent'
[junit4] 2> at org.apache.solr.core.SolrResourceLoader.findClass(SolrResourceLoader.java:449)
[junit4] 2> at org.apache.solr.core.SolrResourceLoader.findClass(SolrResourceLoader.java:381)
[junit4] 2> at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:525)
[junit4] 2> at org.apache.solr.core.SolrCore.createInitInstance(SolrCore.java:581)
[junit4] 2> at org.apache.solr.core.SolrCore.initPlugins(SolrCore.java:2114)
[junit4] 2> at org.apache.solr.core.SolrCore.initPlugins(SolrCore.java:2108)
[junit4] 2> at org.apache.solr.core.SolrCore.initPlugins(SolrCore.java:2141)
[junit4] 2> at org.apache.solr.core.SolrCore.loadSearchComponents(SolrCore.java:1172)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:762)
[junit4] 2> ... 11 more
[junit4] 2> Caused by: java.lang.ClassNotFoundException: solr.clustering.ClusteringComponent
[junit4] 2> at java.net.URLClassLoader$1.run(URLClassLoader.java:365)
[junit4] 2> at java.net.URLClassLoader$1.run(URLClassLoader.java:354)
[junit4] 2> at java.security.AccessController.doPrivileged(Native Method)
[junit4] 2> at java.net.URLClassLoader.findClass(URLClassLoader.java:353)
[junit4] 2> at java.lang.ClassLoader.loadClass(ClassLoader.java:423)
[junit4] 2> at java.net.FactoryURLClassLoader.loadClass(URLClassLoader.java:788)
[junit4] 2> at java.lang.ClassLoader.loadClass(ClassLoader.java:356)
[junit4] 2> at java.lang.Class.forName0(Native Method)
[junit4] 2> at java.lang.Class.forName(Class.java:339)
[junit4] 2> at org.apache.solr.core.SolrResourceLoader.findClass(SolrResourceLoader.java:433)
[junit4] 2> ... 19 more
[junit4] 2>
[junit4] 2> 184711 T569 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4] 2> 184711 T569 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 184805 T569 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:51479
[junit4] 2> 184881 T574 oasc.SolrException.log ERROR null:org.apache.solr.common.SolrException: SolrCore 'collection1' is not available due to init failure: Error loading class 'solr.clustering.ClusteringComponent'
[junit4] 2> at org.apache.solr.core.CoreContainer.getCore(CoreContainer.java:781)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:258)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:158)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4] 2> at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:557)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1075)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4] 2> at org.eclipse.jetty.server.BlockingHttpConnection.handleRequest(BlockingHttpConnection.java:53)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:942)
[junit4] 2> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1004)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:640)
[junit4] 2> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
[junit4] 2> at org.eclipse.jetty.server.BlockingHttpConnection.handle(BlockingHttpConnection.java:72)
[junit4] 2> at org.eclipse.jetty.server.bio.SocketConnector$ConnectorEndPoint.run(SocketConnector.java:264)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error loading class 'solr.clustering.ClusteringComponent'
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:831)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:624)
[junit4] 2> at org.apache.solr.core.CoreContainer.createFromLocal(CoreContainer.java:518)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:553)
[junit4] 2> at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:245)
[junit4] 2> at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:237)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:262)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[junit4] 2> ... 1 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error loading class 'solr.clustering.ClusteringComponent'
[junit4] 2> at org.apache.solr.core.SolrResourceLoader.findClass(SolrResourceLoader.java:449)
[junit4] 2> at org.apache.solr.core.SolrResourceLoader.findClass(SolrResourceLoader.java:381)
[junit4] 2> at org.apache.solr.core.SolrCore.createInstance(SolrCore.java:525)
[junit4] 2> at org.apache.solr.core.SolrCore.createInitInstance(SolrCore.java:581)
[junit4] 2> at org.apache.solr.core.SolrCore.initPlugins(SolrCore.java:2114)
[junit4] 2> at org.apache.solr.core.SolrCore.initPlugins(SolrCore.java:2108)
[junit4] 2> at org.apache.solr.core.SolrCore.initPlugins(SolrCore.java:2141)
[junit4] 2> at org.apache.solr.core.SolrCore.loadSearchComponents(SolrCore.java:1172)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:762)
[junit4] 2> ... 11 more
[junit4] 2> Caused by: java.lang.ClassNotFoundException: solr.clustering.ClusteringComponent
[junit4] 2> at java.net.URLClassLoader$1.run(URLClassLoader.java:365)
[junit4] 2> at java.net.URLClassLoader$1.run(URLClassLoader.java:354)
[junit4] 2> at java.security.AccessController.doPrivileged(Native Method)
[junit4] 2> at java.net.URLClassLoader.findClass(URLClassLoader.java:353)
[junit4] 2> at java.lang.ClassLoader.loadClass(ClassLoader.java:423)
[junit4] 2> at java.net.FactoryURLClassLoader.loadClass(URLClassLoader.java:788)
[junit4] 2> at java.lang.ClassLoader.loadClass(ClassLoader.java:356)
[junit4] 2> at java.lang.Class.forName0(Native Method)
[junit4] 2> at java.lang.Class.forName(Class.java:339)
[junit4] 2> at org.apache.solr.core.SolrResourceLoader.findClass(SolrResourceLoader.java:433)
[junit4] 2> ... 19 more
[junit4] 2>
[junit4] 2> 184899 T569 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1715760334
[junit4] 2> 184904 T569 oejsh.ContextHandler.doStop stopped o.e.j.w.WebAppContext{/test,file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/webapp/web/},C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\webapp\web
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=JettyWebappTest -Dtests.method=testAdminUI -Dtests.seed=E1EC7E8DE0CF3F60 -Dtests.slow=true -Dtests.locale=zh_CN -Dtests.timezone=US/Central -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 1.09s | JettyWebappTest.testAdminUI <<<
[junit4] > Throwable #1: java.io.IOException: Server returned HTTP response code: 500 for URL: http://127.0.0.1:51479/test/
[junit4] > at __randomizedtesting.SeedInfo.seed([E1EC7E8DE0CF3F60:D93E9DDE87A4A4DF]:0)
[junit4] > at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1781)
[junit4] > at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1382)
[junit4] > at java.net.URL.openStream(URL.java:1038)
[junit4] > at org.apache.solr.client.solrj.embedded.JettyWebappTest.testAdminUI(JettyWebappTest.java:98)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=zh_CN, timezone=US/Central
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=2,threads=1,free=84632992,total=225443840
[junit4] 2> NOTE: All tests run in this JVM: [ContentStreamTest, QueryResponseTest, ModifiableSolrParamsTest, SolrExampleXMLTest, SolrExampleBinaryTest, TestSolrProperties, SolrDocumentTest, TestNamedListCodec, TestDocumentObjectBinder, SolrPingTest, SolrExampleStreamingTest, SolrExceptionTest, TestFastInputStream, TestLBHttpSolrServer, IteratorChainTest, FieldAnalysisResponseTest, BasicHttpSolrServerTest, TestJavaBinCodec, MultiCoreExampleJettyTest, TestBatchUpdate, CloudSolrServerTest, AnlysisResponseBaseTest, TestCoreAdmin, SolrExampleStreamingBinaryTest, SolrExampleJettyTest, SolrExampleEmbeddedTest, LargeVolumeBinaryJettyTest, JettyWebappTest]
[junit4] Completed in 1.10s, 1 test, 1 error <<< FAILURES!
[...truncated 63 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:395: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:375: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:194: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:449: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1233: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:876: There were test failures: 46 suites, 272 tests, 1 error, 1 ignored (1 assumption)
Total time: 70 minutes 40 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b99 -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.8.0-ea-b99) - Build #
3065 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3065/
Java: 64bit/jdk1.8.0-ea-b99 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate
Error Message:
expected:<[in]active> but was:<[]active>
Stack Trace:
org.junit.ComparisonFailure: expected:<[in]active> but was:<[]active>
at __randomizedtesting.SeedInfo.seed([48D0DF3C4DFF161:D4EF80BF9A0A2BE5]:0)
at org.junit.Assert.assertEquals(Assert.java:125)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate(SliceStateUpdateTest.java:183)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 9869 lines...]
[junit4] Suite: org.apache.solr.cloud.SliceStateUpdateTest
[junit4] 2> 1525728 T4031 oas.SolrTestCaseJ4.setUp ###Starting testSliceStateUpdate
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137
[junit4] 2> 1525728 T4031 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 1525728 T4032 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1525872 T4031 oasc.ZkTestServer.run start zk server on port:49715
[junit4] 2> 1525872 T4031 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1525892 T4038 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@409e437 name:ZooKeeperConnection Watcher:127.0.0.1:49715 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1525892 T4031 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1525892 T4031 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1525913 T4031 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1525913 T4040 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ace0ac6 name:ZooKeeperConnection Watcher:127.0.0.1:49715/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1525913 T4031 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1525913 T4031 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1525933 T4031 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1525933 T4031 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1525954 T4031 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1525975 T4031 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1525975 T4031 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1525995 T4031 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1525995 T4031 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1526016 T4031 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1526016 T4031 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1526016 T4031 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1526016 T4031 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1526036 T4031 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1526036 T4031 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1526036 T4031 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1526057 T4031 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1526057 T4031 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1526057 T4031 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1526078 T4031 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1526078 T4031 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1526078 T4031 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1526078 T4031 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1526098 T4031 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1526098 T4031 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1526119 T4031 oasc.SliceStateUpdateTest.setUp ####SETUP_START testSliceStateUpdate
[junit4] 2> 1526119 T4033 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14069b6b0950001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 1526119 T4031 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1526139 T4042 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ef18f47 name:ZooKeeperConnection Watcher:127.0.0.1:49715/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1526139 T4031 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1526139 T4031 oascc.SolrZkClient.makePath makePath: /collections/testcore
[junit4] 2> 1526139 T4031 oascc.SolrZkClient.makePath makePath: /collections/testcore/shards
[junit4] 2> 1526160 T4031 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1526160 T4031 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 1526160 T4031 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 1526201 T4031 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4] 2> 1526201 T4031 oasc.CoreContainer.<init> New CoreContainer 1233676781
[junit4] 2> 1526201 T4031 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1526222 T4031 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 1526222 T4031 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 1526242 T4031 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4] 2> 1526263 T4031 oasc.CoreContainer.<init> New CoreContainer 226333534
[junit4] 2> 1526263 T4031 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 1526263 T4031 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1526263 T4031 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1526263 T4031 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1526263 T4031 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1526263 T4031 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1526263 T4031 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1526263 T4031 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1526263 T4031 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1526263 T4031 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1526263 T4031 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1526283 T4031 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49715/solr
[junit4] 2> 1526283 T4031 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1526283 T4031 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1526283 T4044 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@285097a3 name:ZooKeeperConnection Watcher:127.0.0.1:49715 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1526283 T4031 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1526304 T4031 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1526304 T4033 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14069b6b0950003, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 1526304 T4031 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1526325 T4046 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@990d9af name:ZooKeeperConnection Watcher:127.0.0.1:49715/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1526325 T4031 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1526325 T4031 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1526325 T4031 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1526345 T4031 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1526345 T4031 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 1526366 T4031 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 1526366 T4031 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1526386 T4031 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1526386 T4031 oasc.Overseer.start Overseer (id=90188225914929156-127.0.0.1:8983_solr-n_0000000000) starting
[junit4] 2> 1526407 T4031 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1526427 T4048 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1526427 T4031 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1526448 T4031 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1526448 T4031 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1526469 T4047 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1526469 T4049 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1526469 T4049 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1526469 T4049 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1528444 T4047 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1528444 T4047 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1528444 T4047 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1528465 T4046 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> 1529103 T4049 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1529103 T4049 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 1529103 T4049 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1529103 T4049 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1529103 T4049 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1529103 T4049 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 1529103 T4049 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1529103 T4049 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1529185 T4049 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1529206 T4049 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1529206 T4049 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1529227 T4049 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1529453 T4049 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1529453 T4049 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1529453 T4049 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1529474 T4049 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1529474 T4049 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1529515 T4049 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1529515 T4049 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1529515 T4049 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1529515 T4049 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1529515 T4049 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1529515 T4049 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1529535 T4049 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1529535 T4049 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1529535 T4049 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1529535 T4049 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\
[junit4] 2> 1529535 T4049 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4471db1e
[junit4] 2> 1529535 T4049 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\
[junit4] 2> 1529535 T4049 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index/
[junit4] 2> 1529535 T4049 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index' doesn't exist. Creating new index...
[junit4] 2> 1529535 T4049 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index
[junit4] 2> 1529535 T4049 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@399731d9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62c87009)),segFN=segments_1,generation=1}
[junit4] 2> 1529535 T4049 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1529535 T4049 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 1529535 T4049 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1529535 T4049 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1529535 T4049 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1529535 T4049 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1529535 T4049 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 1529535 T4049 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 1529556 T4049 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1529556 T4049 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1529556 T4049 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML]
[junit4] 2> 1529556 T4049 oass.SolrIndexSearcher.<init> Opening Searcher@58cb4936 main
[junit4] 2> 1529556 T4049 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1529556 T4049 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1529556 T4049 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1529576 T4049 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1529659 T4050 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@58cb4936 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1529659 T4049 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1529659 T4049 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 1529659 T4049 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 1529679 T4049 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 1529700 T4049 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1529721 T4049 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1529721 T4049 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 1529721 T4049 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 1529721 T4049 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 1530441 T4047 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1530461 T4046 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> 1530564 T4049 oasc.ZkController.register We are http://127.0.0.1:8983/solr/collection1/ and leader is http://127.0.0.1:8983/solr/collection1/
[junit4] 2> 1530564 T4049 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 1530564 T4049 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 1530564 T4049 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 1530564 T4049 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1530564 T4049 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1530564 T4031 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 1530564 T4031 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1530564 T4031 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1530564 T4031 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1530564 T4031 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1530564 T4031 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1530564 T4031 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1530564 T4031 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1530564 T4031 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1530564 T4031 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1530564 T4031 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1530585 T4031 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:49715/solr
[junit4] 2> 1530585 T4031 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1530585 T4031 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1530585 T4053 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1940cae7 name:ZooKeeperConnection Watcher:127.0.0.1:49715 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1530585 T4031 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1530606 T4031 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1530606 T4031 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1530626 T4055 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@e40c661 name:ZooKeeperConnection Watcher:127.0.0.1:49715/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1530626 T4031 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1530626 T4031 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1531943 T4031 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 1531943 T4031 oasc.ZkController.createEphemeralLiveNode Found a previous node that still exists while trying to register a new live node /live_nodes/127.0.0.1:8983_solr - removing existing node to create another.
[junit4] 2> 1531943 T4031 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 1531943 T4046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1531943 T4055 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 1531943 T4046 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 1531943 T4046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 1531943 T4055 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 1531943 T4046 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 1531964 T4056 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1531964 T4056 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1531964 T4056 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1531964 T4056 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1531964 T4056 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 1531964 T4056 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1531964 T4056 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1531964 T4056 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1531964 T4056 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 1531964 T4056 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1531984 T4056 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1532046 T4056 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1532067 T4056 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1532087 T4056 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1532087 T4056 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1532273 T4056 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1532273 T4056 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1532273 T4056 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1532293 T4056 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1532293 T4056 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1532314 T4056 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1532314 T4056 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1532334 T4056 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1532334 T4056 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1532334 T4056 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1532334 T4056 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1532334 T4056 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1532334 T4056 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1532334 T4056 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1532334 T4056 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\
[junit4] 2> 1532334 T4056 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@4471db1e
[junit4] 2> 1532334 T4056 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\
[junit4] 2> 1532334 T4056 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index/
[junit4] 2> 1532334 T4056 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index' doesn't exist. Creating new index...
[junit4] 2> 1532334 T4056 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index
[junit4] 2> 1532334 T4056 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@52fead8e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6e7e965a)),segFN=segments_1,generation=1}
[junit4] 2> 1532334 T4056 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1532334 T4056 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 1532334 T4056 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1532334 T4056 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1532334 T4056 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1532334 T4056 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1532334 T4056 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1532334 T4056 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1532334 T4056 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1532334 T4056 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1532334 T4056 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1532334 T4056 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 1532355 T4056 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 1532355 T4056 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1532355 T4056 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1532376 T4056 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML]
[junit4] 2> 1532376 T4056 oass.SolrIndexSearcher.<init> Opening Searcher@77c6faf2 main
[junit4] 2> 1532376 T4056 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1532376 T4056 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1532376 T4056 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1532376 T4056 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1532396 T4057 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@77c6faf2 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1532417 T4056 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1532417 T4056 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 1532417 T4056 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 1532437 T4056 oasc.ZkController.register We are http://127.0.0.1:8983/solr/collection1/ and leader is http://127.0.0.1:8983/solr/collection1/
[junit4] 2> 1532437 T4056 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 1532437 T4056 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 1532437 T4056 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 1532437 T4056 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1532437 T4047 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1532437 T4056 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1532437 T4047 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"active",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1532437 T4031 oasc.SliceStateUpdateTest.setUp ####SETUP_END testSliceStateUpdate
[junit4] 2> 1532458 T4031 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1532458 T4060 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3e152f32 name:ZooKeeperConnection Watcher:127.0.0.1:49715/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1532458 T4031 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1532458 T4046 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> 1532458 T4055 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> 1532458 T4047 oasc.Overseer$ClusterStateUpdater.updateState node=core_node1 is already registered
[junit4] 2> 1532458 T4047 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1532458 T4047 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 1532458 T4033 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14069b6b0950007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 1532478 T4047 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"active",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1532499 T4055 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> 1579993 T4031 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1233676781
[junit4] 2> 1580026 T4047 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1580026 T4047 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1580026 T4047 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 1580043 T4046 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> 1580043 T4055 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> 1582106 T4031 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1582106 T4031 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1582106 T4031 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1f097fdd
[junit4] 2> 1582106 T4031 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1582106 T4031 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1582122 T4031 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1582122 T4031 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1582122 T4031 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1582122 T4031 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1582122 T4031 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index;done=false>>]
[junit4] 2> 1582122 T4031 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index
[junit4] 2> 1582122 T4031 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\;done=false>>]
[junit4] 2> 1582122 T4031 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\
[junit4] 2> 1582122 T4047 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90188225914929156-127.0.0.1:8983_solr-n_0000000000) am no longer a leader.
[junit4] 2> 1582122 T4031 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=226333534
[junit4] 2> 1582122 T4055 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1582139 T4055 oasc.Overseer.start Overseer (id=90188225914929158-127.0.0.1:8983_solr-n_0000000001) starting
[junit4] 2> 1582139 T4062 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1582155 T4055 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1582155 T4061 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1582155 T4055 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4] 2> 1582155 T4055 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4] 2> 1582155 T4055 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 1582155 T4055 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 1582155 T4055 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 1582155 T4061 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1582155 T4061 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1582188 T4055 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> 1583178 T4031 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1583178 T4031 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1583178 T4031 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@634f1412
[junit4] 2> 1583195 T4031 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1583195 T4031 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1583195 T4031 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1583195 T4031 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1583195 T4031 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1583195 T4031 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1583195 T4031 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\;done=false>>]
[junit4] 2> 1583195 T4031 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\
[junit4] 2> 1583195 T4031 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index;done=false>>]
[junit4] 2> 1583211 T4031 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376163115137\data2\index
[junit4] 2> 1583211 T4061 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90188225914929158-127.0.0.1:8983_solr-n_0000000001) am no longer a leader.
[junit4] 2> 1583211 T4055 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1583211 T4055 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1583211 T4055 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1583211 T4031 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:49715 49715
[junit4] 2> 1585433 T4031 oas.SolrTestCaseJ4.tearDown ###Ending testSliceStateUpdate
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SliceStateUpdateTest -Dtests.method=testSliceStateUpdate -Dtests.seed=48D0DF3C4DFF161 -Dtests.slow=true -Dtests.locale=ar_EG -Dtests.timezone=America/Santiago -Dtests.file.encoding=UTF-8
[junit4] FAILURE 59.7s | SliceStateUpdateTest.testSliceStateUpdate <<<
[junit4] > Throwable #1: org.junit.ComparisonFailure: expected:<[in]active> but was:<[]active>
[junit4] > at __randomizedtesting.SeedInfo.seed([48D0DF3C4DFF161:D4EF80BF9A0A2BE5]:0)
[junit4] > at org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate(SliceStateUpdateTest.java:183)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 1585448 T4031 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=100), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=100)), sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=ar_EG, timezone=America/Santiago
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=2,threads=1,free=180867472,total=503316480
[junit4] 2> NOTE: All tests run in this JVM: [TestUtils, BasicDistributedZk2Test, TestExtendedDismaxParser, TestOmitPositions, TestCodecSupport, TestAddFieldRealTimeGet, TestSchemaNameResource, TestMultiCoreConfBootstrap, TestPhraseSuggestions, TestDynamicFieldResource, TestQueryTypes, ChaosMonkeySafeLeaderTest, TestStressVersions, DistributedTermsComponentTest, DisMaxRequestHandlerTest, StressHdfsTest, HdfsRecoveryZkTest, TestHashPartitioner, ZkNodePropsTest, TestFieldTypeResource, TestCloudManagedSchemaAddField, BasicFunctionalityTest, DocValuesTest, ResourceLoaderTest, OpenExchangeRatesOrgProviderTest, DebugComponentTest, SimplePostToolTest, TestRandomMergePolicy, TestDistributedGrouping, TestRangeQuery, SpellPossibilityIteratorTest, TestSearchPerf, TermsComponentTest, SuggesterWFSTTest, ShardSplitTest, TestCharFilters, TestLFUCache, IndexSchemaTest, MinimalSchemaTest, NotRequiredUniqueKeyTest, SyncSliceTest, BadIndexSchemaTest, TermVectorComponentTest, LeaderElectionTest, DocumentBuilderTest, TestDynamicFieldCollectionResource, SynonymTokenizerTest, RAMDirectoryFactoryTest, TestSolrDeletionPolicy2, AlternateDirectoryTest, UpdateParamsTest, TestSolrXml, PolyFieldTest, SOLR749Test, CoreContainerCoreInitFailuresTest, BasicDistributedZkTest, SliceStateTest, TestSerializedLuceneMatchVersion, TestDistributedSearch, UnloadDistributedZkTest, TestLuceneMatchVersion, TestCollationField, TestDFRSimilarityFactory, XmlUpdateRequestHandlerTest, CoreMergeIndexesAdminHandlerTest, SimpleFacetsTest, PingRequestHandlerTest, SpatialFilterTest, CoreAdminCreateDiscoverTest, QueryParsingTest, TestReload, TestSchemaSimilarityResource, DateMathParserTest, TestRemoteStreaming, TestCSVResponseWriter, SolrInfoMBeanTest, TestLazyCores, StatelessScriptUpdateProcessorFactoryTest, ShardRoutingTest, TestInfoStreamLogging, SpellCheckComponentTest, CachingDirectoryFactoryTest, ClusterStateUpdateTest, TestFieldCollectionResource, TestMergePolicyConfig, QueryElevationComponentTest, WordBreakSolrSpellCheckerTest, SearchHandlerTest, TestManagedSchemaFieldResource, TestFastOutputStream, TestStressRecovery, TestClassNameShortening, TestSurroundQueryParser, PathHierarchyTokenizerFactoryTest, HighlighterTest, MBeansHandlerTest, TestSolr4Spatial, TestFastLRUCache, ParsingFieldUpdateProcessorsTest, TestFunctionQuery, TestSort, TestCoreContainer, NoCacheHeaderTest, DistanceFunctionTest, PeerSyncTest, OpenCloseCoreStressTest, DocumentAnalysisRequestHandlerTest, CurrencyFieldXmlFileTest, FastVectorHighlighterTest, TestNumberUtils, TestConfig, DirectUpdateHandlerTest, TestStressReorder, CircularListTest, TestSolrQueryParserDefaultOperatorResource, TestRandomFaceting, TestReloadAndDeleteDocs, TestTrie, CSVRequestHandlerTest, RequiredFieldsTest, TestLMJelinekMercerSimilarityFactory, DefaultValueUpdateProcessorTest, SuggesterTSTTest, TestPostingsSolrHighlighter, TestDocSet, TestReplicationHandler, SliceStateUpdateTest]
[junit4] Completed in 59.80s, 1 test, 1 failure <<< FAILURES!
[...truncated 582 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:395: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:375: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:181: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:449: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1233: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:876: There were test failures: 319 suites, 1350 tests, 1 failure, 44 ignored (11 assumptions)
Total time: 67 minutes 54 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b99 -XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.8.0-ea-b99) - Build #
3064 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3064/
Java: 64bit/jdk1.8.0-ea-b99 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate
Error Message:
expected:<[in]active> but was:<[]active>
Stack Trace:
org.junit.ComparisonFailure: expected:<[in]active> but was:<[]active>
at __randomizedtesting.SeedInfo.seed([EF3D981647956882:3F5F155A1940B206]:0)
at org.junit.Assert.assertEquals(Assert.java:125)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate(SliceStateUpdateTest.java:183)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
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:70)
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:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
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:358)
at java.lang.Thread.run(Thread.java:724)
Build Log:
[...truncated 9917 lines...]
[junit4] Suite: org.apache.solr.cloud.SliceStateUpdateTest
[junit4] 2> 1675057 T5604 oas.SolrTestCaseJ4.setUp ###Starting testSliceStateUpdate
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756
[junit4] 2> 1675059 T5604 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 1675061 T5605 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1675186 T5604 oasc.ZkTestServer.run start zk server on port:60095
[junit4] 2> 1675191 T5604 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1675212 T5611 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@780524a7 name:ZooKeeperConnection Watcher:127.0.0.1:60095 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1675212 T5604 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1675213 T5604 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1675227 T5606 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14067c4648e0000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 1675233 T5604 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1675241 T5613 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@76c2d5d7 name:ZooKeeperConnection Watcher:127.0.0.1:60095/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1675241 T5604 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1675241 T5604 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1675257 T5604 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1675268 T5604 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1675278 T5604 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1675295 T5604 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1675297 T5604 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1675322 T5604 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1675325 T5604 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1675343 T5604 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1675346 T5604 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1675358 T5604 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1675360 T5604 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1675374 T5604 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1675375 T5604 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1675386 T5604 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1675389 T5604 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1675401 T5604 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1675402 T5604 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1675415 T5604 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1675416 T5604 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1675428 T5604 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1675428 T5604 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1675440 T5604 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1675443 T5604 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1675458 T5604 oasc.SliceStateUpdateTest.setUp ####SETUP_START testSliceStateUpdate
[junit4] 2> 1675459 T5606 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14067c4648e0001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 1675462 T5604 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1675472 T5615 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@72fd4fd7 name:ZooKeeperConnection Watcher:127.0.0.1:60095/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1675472 T5604 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1675474 T5604 oascc.SolrZkClient.makePath makePath: /collections/testcore
[junit4] 2> 1675483 T5604 oascc.SolrZkClient.makePath makePath: /collections/testcore/shards
[junit4] 2> 1675514 T5604 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1675514 T5604 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 1675515 T5604 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 1675569 T5604 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4] 2> 1675587 T5604 oasc.CoreContainer.<init> New CoreContainer 1089655556
[junit4] 2> 1675588 T5604 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1675589 T5604 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 1675589 T5604 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 1675616 T5604 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4] 2> 1675628 T5604 oasc.CoreContainer.<init> New CoreContainer 937723241
[junit4] 2> 1675628 T5604 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 1675631 T5604 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1675631 T5604 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1675631 T5604 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1675631 T5604 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1675632 T5604 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1675632 T5604 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1675632 T5604 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1675632 T5604 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1675632 T5604 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1675632 T5604 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1675643 T5604 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60095/solr
[junit4] 2> 1675643 T5604 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1675646 T5604 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1675650 T5617 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@778203f6 name:ZooKeeperConnection Watcher:127.0.0.1:60095 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1675652 T5604 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1675655 T5604 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1675663 T5604 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1675666 T5619 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@58d4a876 name:ZooKeeperConnection Watcher:127.0.0.1:60095/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1675666 T5604 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1675670 T5604 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 1675676 T5604 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 1675682 T5604 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 1675687 T5604 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 1675690 T5604 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 1675696 T5604 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 1675708 T5604 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1675713 T5604 oasc.Overseer.start Overseer (id=90186085745688580-127.0.0.1:8983_solr-n_0000000000) starting
[junit4] 2> 1675723 T5604 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 1675732 T5621 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1675733 T5604 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 1675738 T5604 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 1675741 T5604 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1675746 T5620 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1675749 T5622 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1675749 T5622 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1675751 T5622 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1677642 T5620 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1677646 T5620 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1677646 T5620 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 1677669 T5619 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> 1678268 T5622 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1678268 T5622 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 1678268 T5622 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1678269 T5622 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1678269 T5622 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1678272 T5622 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 1678275 T5622 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1678276 T5622 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1678329 T5622 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1678359 T5622 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1678361 T5622 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1678370 T5622 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1678560 T5622 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1678562 T5622 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1678563 T5622 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1678570 T5622 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1678573 T5622 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1678592 T5622 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1678597 T5622 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1678602 T5622 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1678603 T5622 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1678603 T5622 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1678603 T5622 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1678606 T5622 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1678606 T5622 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1678606 T5622 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1678607 T5622 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\
[junit4] 2> 1678608 T5622 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@13d32cd2
[junit4] 2> 1678608 T5622 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\
[junit4] 2> 1678608 T5622 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index/
[junit4] 2> 1678608 T5622 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index' doesn't exist. Creating new index...
[junit4] 2> 1678609 T5622 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index
[junit4] 2> 1678611 T5622 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@21bef3ea lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@665d6bec)),segFN=segments_1,generation=1}
[junit4] 2> 1678611 T5622 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1678611 T5622 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 1678617 T5622 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1678617 T5622 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1678617 T5622 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1678618 T5622 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1678618 T5622 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1678619 T5622 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1678619 T5622 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1678619 T5622 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1678620 T5622 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1678620 T5622 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 1678623 T5622 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 1678623 T5622 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1678623 T5622 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1678623 T5622 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1678624 T5622 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1678624 T5622 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1678624 T5622 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1678625 T5622 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1678625 T5622 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1678627 T5622 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1678628 T5622 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1678628 T5622 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1678628 T5622 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1678629 T5622 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1678629 T5622 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 1678630 T5622 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 1678638 T5622 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1678638 T5622 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1678639 T5622 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML]
[junit4] 2> 1678641 T5622 oass.SolrIndexSearcher.<init> Opening Searcher@322e30ec main
[junit4] 2> 1678644 T5622 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1678644 T5622 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1678644 T5622 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1678659 T5622 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1678732 T5623 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@322e30ec main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1678742 T5622 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1678743 T5622 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 1678743 T5622 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 1678756 T5622 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 1678773 T5622 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1678779 T5622 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 1678779 T5622 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 1678779 T5622 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 1678780 T5622 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 1679572 T5620 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1679610 T5619 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> 1679688 T5622 oasc.ZkController.register We are http://127.0.0.1:8983/solr/collection1/ and leader is http://127.0.0.1:8983/solr/collection1/
[junit4] 2> 1679688 T5622 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 1679690 T5622 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 1679690 T5622 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 1679690 T5622 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1679697 T5622 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1679701 T5604 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 1679702 T5604 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 1679703 T5604 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1679704 T5604 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 1679704 T5604 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1679706 T5604 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1679706 T5604 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1679707 T5604 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1679707 T5604 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1679708 T5604 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1679708 T5604 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 1679720 T5604 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:60095/solr
[junit4] 2> 1679720 T5604 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 1679721 T5604 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1679726 T5626 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@29ae15a0 name:ZooKeeperConnection Watcher:127.0.0.1:60095 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1679726 T5604 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1679730 T5604 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 1679740 T5604 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1679745 T5628 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6241358c name:ZooKeeperConnection Watcher:127.0.0.1:60095/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1679745 T5604 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1679761 T5604 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 1681028 T5604 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 1681031 T5604 oasc.ZkController.createEphemeralLiveNode Found a previous node that still exists while trying to register a new live node /live_nodes/127.0.0.1:8983_solr - removing existing node to create another.
[junit4] 2> 1681033 T5628 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 1681033 T5604 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 1681031 T5619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1681038 T5619 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 1681038 T5619 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1681039 T5628 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 1681049 T5629 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1681049 T5629 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1681052 T5629 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 1681052 T5629 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 1681052 T5629 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 1681052 T5629 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 1681054 T5629 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 1681054 T5629 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 1681056 T5629 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 1681058 T5629 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1681060 T5629 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1681140 T5629 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1681196 T5629 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1681199 T5629 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1681208 T5629 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1681418 T5629 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1681418 T5629 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 1681420 T5629 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1681428 T5629 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 1681431 T5629 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1681461 T5629 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1681465 T5629 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 1681470 T5629 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1681473 T5629 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1681473 T5629 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1681473 T5629 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1681476 T5629 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 1681476 T5629 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 1681476 T5629 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1681476 T5629 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\
[junit4] 2> 1681476 T5629 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@13d32cd2
[junit4] 2> 1681477 T5629 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\
[junit4] 2> 1681477 T5629 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index/
[junit4] 2> 1681477 T5629 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index' doesn't exist. Creating new index...
[junit4] 2> 1681477 T5629 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index
[junit4] 2> 1681480 T5629 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@676a409 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@3234e272)),segFN=segments_1,generation=1}
[junit4] 2> 1681480 T5629 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1681480 T5629 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 1681486 T5629 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1681486 T5629 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 1681486 T5629 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1681487 T5629 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 1681487 T5629 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1681487 T5629 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 1681487 T5629 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1681490 T5629 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1681491 T5629 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 1681491 T5629 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 1681492 T5629 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 1681492 T5629 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 1681493 T5629 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1681493 T5629 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1681493 T5629 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1681495 T5629 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1681495 T5629 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1681496 T5629 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1681496 T5629 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1681497 T5629 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1681497 T5629 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1681498 T5629 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1681498 T5629 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1681501 T5629 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 1681501 T5629 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 1681502 T5629 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 1681508 T5629 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1681508 T5629 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 1681511 T5629 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML]
[junit4] 2> 1681513 T5629 oass.SolrIndexSearcher.<init> Opening Searcher@10650937 main
[junit4] 2> 1681516 T5629 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1681516 T5629 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1681517 T5629 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 1681520 T5629 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 1681546 T5630 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10650937 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1681555 T5629 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1681555 T5629 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 1681555 T5629 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 1681560 T5620 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1681562 T5620 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"active",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1681575 T5629 oasc.ZkController.register We are http://127.0.0.1:8983/solr/collection1/ and leader is http://127.0.0.1:8983/solr/collection1/
[junit4] 2> 1681575 T5629 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 1681575 T5629 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 1681575 T5629 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 1681575 T5629 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1681578 T5620 oasc.Overseer$ClusterStateUpdater.updateState node=core_node1 is already registered
[junit4] 2> 1681578 T5620 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1681578 T5620 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 1681578 T5629 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1681582 T5604 oasc.SliceStateUpdateTest.setUp ####SETUP_END testSliceStateUpdate
[junit4] 2> 1681584 T5604 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1681589 T5633 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3ca3ede8 name:ZooKeeperConnection Watcher:127.0.0.1:60095/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1681589 T5604 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1681589 T5620 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"active",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1681592 T5619 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> 1681592 T5628 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> 1681597 T5606 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14067c4648e0007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 1681602 T5628 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> 1733238 T5604 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1089655556
[junit4] 2> 1733346 T5620 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1733348 T5620 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1733348 T5620 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 1733358 T5619 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> 1733359 T5628 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> 1735679 T5604 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1735679 T5604 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1735687 T5604 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@368596cd
[junit4] 2> 1735704 T5604 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1735704 T5604 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1735706 T5604 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1735707 T5604 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1735712 T5604 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1735713 T5604 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1735713 T5604 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\;done=false>>]
[junit4] 2> 1735714 T5604 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\
[junit4] 2> 1735714 T5604 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index;done=false>>]
[junit4] 2> 1735715 T5604 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index
[junit4] 2> 1735720 T5620 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90186085745688580-127.0.0.1:8983_solr-n_0000000000) am no longer a leader.
[junit4] 2> 1735725 T5604 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=937723241
[junit4] 2> 1735725 T5606 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14067c4648e0004, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 1735734 T5628 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1735748 T5628 oasc.Overseer.start Overseer (id=90186085745688582-127.0.0.1:8983_solr-n_0000000001) starting
[junit4] 2> 1735783 T5635 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1735787 T5634 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1735792 T5628 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1735801 T5634 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1735804 T5628 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4] 2> 1735805 T5628 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4] 2> 1735805 T5628 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 1735805 T5628 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 1735808 T5628 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 1735808 T5634 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 1735865 T5628 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> 1736940 T5604 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1736940 T5604 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1736943 T5604 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4ce17584
[junit4] 2> 1736962 T5604 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1736962 T5604 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1736962 T5604 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1736962 T5604 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1736964 T5604 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1736964 T5604 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1736964 T5604 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\;done=false>>]
[junit4] 2> 1736966 T5604 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\
[junit4] 2> 1736966 T5604 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index;done=false>>]
[junit4] 2> 1736967 T5604 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376130458756\data2\index
[junit4] 2> 1736968 T5634 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90186085745688582-127.0.0.1:8983_solr-n_0000000001) am no longer a leader.
[junit4] 2> 1736974 T5606 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14067c4648e0006, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 1736974 T5628 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1736978 T5628 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1736978 T5604 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:60095 60095
[junit4] 2> 1736979 T5628 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1739718 T5604 oas.SolrTestCaseJ4.tearDown ###Ending testSliceStateUpdate
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SliceStateUpdateTest -Dtests.method=testSliceStateUpdate -Dtests.seed=EF3D981647956882 -Dtests.slow=true -Dtests.locale=ca_ES -Dtests.timezone=America/Louisville -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 64.7s | SliceStateUpdateTest.testSliceStateUpdate <<<
[junit4] > Throwable #1: org.junit.ComparisonFailure: expected:<[in]active> but was:<[]active>
[junit4] > at __randomizedtesting.SeedInfo.seed([EF3D981647956882:3F5F155A1940B206]:0)
[junit4] > at org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate(SliceStateUpdateTest.java:183)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 1739763 T5604 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=396), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=396)), sim=DefaultSimilarity, locale=ca_ES, timezone=America/Louisville
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=2,threads=1,free=250532368,total=535822336
[junit4] 2> NOTE: All tests run in this JVM: [DateFieldTest, TestCSVLoader, TestDynamicFieldResource, TestRandomFaceting, TestIndexingPerformance, CollectionsAPIDistributedZkTest, OutputWriterTest, CoreMergeIndexesAdminHandlerTest, ChaosMonkeyNothingIsSafeTest, TestBadConfig, XmlUpdateRequestHandlerTest, TestSystemIdResolver, SliceStateTest, TermsComponentTest, WordBreakSolrSpellCheckerTest, SolrCmdDistributorTest, DirectSolrConnectionTest, SpatialFilterTest, TestSolrDeletionPolicy2, TestReloadAndDeleteDocs, TestSuggestSpellingConverter, ClusterStateTest, BadCopyFieldTest, TestDefaultSimilarityFactory, ZkNodePropsTest, DistributedTermsComponentTest, BasicDistributedZkTest, AlternateDirectoryTest, BadIndexSchemaTest, DirectSolrSpellCheckerTest, DisMaxRequestHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, SearchHandlerTest, TestCollationField, TestIBSimilarityFactory, TestMultiCoreConfBootstrap, TestTrie, CachingDirectoryFactoryTest, TestShardHandlerFactory, QueryResultKeyTest, TestMaxScoreQueryParser, QueryEqualityTest, MinimalSchemaTest, MBeansHandlerTest, HdfsBasicDistributedZk2Test, HdfsRecoveryZkTest, FileUtilsTest, TestBM25SimilarityFactory, NoCacheHeaderTest, TestFieldCollectionResource, OverseerCollectionProcessorTest, QueryElevationComponentTest, DocValuesTest, TestSchemaResource, ChaosMonkeySafeLeaderTest, TestSolrJ, CircularListTest, TestRemoteStreaming, LukeRequestHandlerTest, TestReversedWildcardFilterFactory, HdfsDirectoryTest, SolrRequestParserTest, TestWriterPerf, SolrPluginUtilsTest, HdfsLockFactoryTest, SoftAutoCommitTest, TestFieldResource, TestRealTimeGet, SpellingQueryConverterTest, UpdateRequestProcessorFactoryTest, SuggesterFSTTest, OpenExchangeRatesOrgProviderTest, OverseerTest, ResponseLogComponentTest, TestInfoStreamLogging, HighlighterTest, FullSolrCloudDistribCmdsTest, StatelessScriptUpdateProcessorFactoryTest, TestOmitPositions, CacheHeaderTest, SolrTestCaseJ4Test, PrimUtilsTest, SOLR749Test, TestWordDelimiterFilterFactory, FieldMutatingUpdateProcessorTest, SchemaVersionSpecificBehaviorTest, TestIndexSearcher, TestDistributedSearch, TestDocSet, StandardRequestHandlerTest, TestQuerySenderListener, BinaryUpdateRequestHandlerTest, TestConfig, TestCloudManagedSchemaAddField, TestFastLRUCache, ConvertedLegacyTest, TestFunctionQuery, TestManagedSchemaFieldResource, ZkCLITest, TestDocumentBuilder, DirectUpdateHandlerTest, LeaderElectionTest, IndexSchemaTest, TestJmxMonitoredMap, TestClassNameShortening, TestRandomDVFaceting, HdfsSyncSliceTest, PreAnalyzedFieldTest, PingRequestHandlerTest, TestSolrXml, TestStressLucene, BlockCacheTest, TestStressRecovery, TestSolrQueryParserDefaultOperatorResource, TestAddFieldRealTimeGet, SimpleFacetsTest, CopyFieldTest, TestReplicationHandler, BasicZkTest, HdfsChaosMonkeySafeLeaderTest, ShardRoutingTest, TestDynamicFieldCollectionResource, SolrCoreCheckLockOnStartupTest, TestLMDirichletSimilarityFactory, TestSolrXMLSerializer, BadComponentTest, BasicFunctionalityTest, TestExtendedDismaxParser, URLClassifyProcessorTest, RecoveryZkTest, DebugComponentTest, TestSort, AssignTest, UniqFieldsUpdateProcessorFactoryTest, TestManagedSchema, TestSolrQueryParser, ParsingFieldUpdateProcessorsTest, HardAutoCommitTest, TestRecovery, PluginInfoTest, ZkControllerTest, TestSearchPerf, TestFastOutputStream, TestPostingsSolrHighlighter, LegacyHTMLStripCharFilterTest, TestCodecSupport, TestLuceneMatchVersion, StatsComponentTest, TestRandomMergePolicy, RAMDirectoryFactoryTest, CustomCollectionTest, ZkSolrClientTest, TestSweetSpotSimilarityFactory, IndexSchemaRuntimeFieldTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, ClusterStateUpdateTest, TestZkChroot, ShardRoutingCustomTest, SliceStateUpdateTest]
[junit4] Completed in 64.81s, 1 test, 1 failure <<< FAILURES!
[...truncated 465 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:395: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:375: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:181: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:449: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1233: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:876: There were test failures: 319 suites, 1350 tests, 1 failure, 44 ignored (11 assumptions)
Total time: 67 minutes 21 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b99 -XX:-UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure