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/07/26 18:57:00 UTC
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.8.0-ea-b99) - Build
# 3076 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3076/
Java: 64bit/jdk1.8.0-ea-b99 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
REGRESSION: org.apache.solr.client.solrj.SolrExampleXMLTest.testStatistics
Error Message:
IOException occured when talking to server at: https://127.0.0.1:58881/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:58881/solr/collection1
at __randomizedtesting.SeedInfo.seed([A346D45C556D36E1:54EFE738F940EC09]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:436)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:168)
at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:863)
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)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
... 45 more
Build Log:
[...truncated 10835 lines...]
[junit4] Suite: org.apache.solr.client.solrj.SolrExampleXMLTest
[junit4] 1> 8805 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] WARN org.apache.solr.SolrTestCaseJ4 – startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 1> 8874 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.SolrJettyTestBase – Randomized ssl (true) and clientAuth (false)
[junit4] 1> 8875 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ####initCore
[junit4] 1> 8878 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ####initCore end
[junit4] 1> 9320 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.eclipse.jetty.server.Server – jetty-8.1.10.v20130312
[junit4] 1> 9897 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.eclipse.jetty.util.ssl.SslContextFactory – Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4] 1> 9959 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.eclipse.jetty.server.AbstractConnector – Started SslSelectChannelConnector@127.0.0.1:58881
[junit4] 1> 10013 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init()
[junit4] 1> 10015 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.SolrResourceLoader – JNDI not configured for solr (NoInitialContextEx)
[junit4] 1> 10016 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.SolrResourceLoader – using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr
[junit4] 1> 10016 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.SolrResourceLoader – new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\'
[junit4] 1> 10066 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.ConfigSolr – Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\solr.xml
[junit4] 1> 10104 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.ConfigSolrXml – Config-defined core root directory:
[junit4] 1> 10112 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.CoreContainer – New CoreContainer 1162884891
[junit4] 1> 10112 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.CoreContainer – Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\]
[junit4] 1> 10168 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting socketTimeout to: 0
[junit4] 1> 10169 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting urlScheme to: http://
[junit4] 1> 10170 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting connTimeout to: 0
[junit4] 1> 10171 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maxConnectionsPerHost to: 20
[junit4] 1> 10171 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting corePoolSize to: 0
[junit4] 1> 10172 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maximumPoolSize to: 2147483647
[junit4] 1> 10173 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maxThreadIdleTime to: 5
[junit4] 1> 10173 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting sizeOfQueue to: -1
[junit4] 1> 10174 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting fairnessPolicy to: false
[junit4] 1> 10174 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 1> 10183 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.CoresLocator – Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr
[junit4] 1> 10206 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.CoresLocator – Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\
[junit4] 1> 10208 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.CoresLocator – Found 1 core definitions
[junit4] 1> 10211 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.CoreContainer – Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1
[junit4] 1> 10212 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrResourceLoader – new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\'
[junit4] 1> 10365 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrConfig – Adding specified lib dirs to ClassLoader
[junit4] 1> 10371 [coreLoadExecutor-37-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\contrib\extraction\lib).
[junit4] 1> 10371 [coreLoadExecutor-37-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4] 1> 10373 [coreLoadExecutor-37-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\contrib\clustering\lib).
[junit4] 1> 10373 [coreLoadExecutor-37-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4] 1> 10375 [coreLoadExecutor-37-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\contrib\langid\lib).
[junit4] 1> 10376 [coreLoadExecutor-37-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4] 1> 10377 [coreLoadExecutor-37-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\contrib\velocity\lib).
[junit4] 1> 10378 [coreLoadExecutor-37-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4] 1> 10491 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.update.SolrIndexConfig – IndexWriter infoStream solr logging is enabled
[junit4] 1> 10495 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrConfig – Using Lucene MatchVersion: LUCENE_50
[junit4] 1> 10597 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.Config – Loaded SolrConfig: solrconfig.xml
[junit4] 1> 10612 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.schema.IndexSchema – Reading Solr Schema from schema.xml
[junit4] 1> 10692 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.schema.IndexSchema – [collection1] Schema name=example
[junit4] 1> 12097 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.schema.IndexSchema – unique key field: id
[junit4] 1> 12707 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider – Reloading exchange rates from file currency.xml
[junit4] 1> 12722 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider – Reloading exchange rates from file currency.xml
[junit4] 1> 13004 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – org.apache.solr.core.MockDirectoryFactory
[junit4] 1> 13005 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleXMLTest-1374857398878\
[junit4] 1> 13012 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.JmxMonitoredMap – JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1734ad03
[junit4] 1> 13060 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 1> 13061 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4] 1> 13098 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory – return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleXMLTest-1374857398878\
[junit4] 1> 13100 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleXMLTest-1374857398878\index/
[junit4] 1> 13100 [coreLoadExecutor-37-thread-1] WARN org.apache.solr.core.SolrCore – [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleXMLTest-1374857398878\index' doesn't exist. Creating new index...
[junit4] 1> 13101 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory – return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleXMLTest-1374857398878\index
[junit4] 1> 13105 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_1,generation=1}
[junit4] 1> 13105 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – newest commit generation = 1
[junit4] 1> 13145 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – created json: solr.JSONResponseWriter
[junit4] 1> 13146 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 1> 13152 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – created velocity: solr.VelocityResponseWriter
[junit4] 1> 13198 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – created xslt: solr.XSLTResponseWriter
[junit4] 1> 13206 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.response.XSLTResponseWriter – xsltCacheLifetimeSeconds=5
[junit4] 1> 13639 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /select: solr.SearchHandler
[junit4] 1> 13640 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /query: solr.SearchHandler
[junit4] 1> 13664 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /get: solr.RealTimeGetHandler
[junit4] 1> 13666 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /browse: solr.SearchHandler
[junit4] 1> 13669 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update: solr.UpdateRequestHandler
[junit4] 1> 13691 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/json: solr.JsonUpdateRequestHandler
[junit4] 1> 13722 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/csv: solr.CSVRequestHandler
[junit4] 1> 13722 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 1> 13730 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 1> 13731 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 1> 13732 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 1> 13733 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 1> 13734 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 1> 13777 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /admin/: solr.admin.AdminHandlers
[junit4] 1> 13799 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /admin/ping: solr.PingRequestHandler
[junit4] 1> 13822 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /debug/dump: solr.DumpRequestHandler
[junit4] 1> 13892 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /replication: solr.ReplicationHandler
[junit4] 1> 13892 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 13894 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /spell: solr.SearchHandler
[junit4] 1> 13894 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 13895 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /tvrh: solr.SearchHandler
[junit4] 1> 13895 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 13896 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /terms: solr.SearchHandler
[junit4] 1> 13897 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 13897 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.RequestHandlers – created /elevate: solr.SearchHandler
[junit4] 1> 13905 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4] 1> 13907 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4] 1> 13910 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4] 1> 13913 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6dcf55bf main
[junit4] 1> 13931 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – Hard AutoCommit: if uncommited for 15000ms;
[junit4] 1> 13931 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.SolrCore – Soft AutoCommit: disabled
[junit4] 1> 13933 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Initializing spell checkers
[junit4] 1> 14009 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.spelling.DirectSolrSpellChecker – init: {name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 1> 14087 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – No queryConverter defined, using default converter
[junit4] 1> 14108 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.handler.component.QueryElevationComponent – Loading QueryElevation from: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\solr\collection1\conf\elevate.xml
[junit4] 1> 14259 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.handler.ReplicationHandler – Commits will be reserved for 10000
[junit4] 1> 14261 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6dcf55bf main{StandardDirectoryReader(segments_1:1)}
[junit4] 1> 14611 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] webapp=null path=null params={event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false} hits=0 status=0 QTime=342
[junit4] 1> 14615 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 14616 [searcherExecutor-38-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Loading spell index for spellchecker: default
[junit4] 1> 14616 [searcherExecutor-38-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Loading spell index for spellchecker: wordbreak
[junit4] 1> 14622 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6dcf55bf main{StandardDirectoryReader(segments_1:1)}
[junit4] 1> 14636 [coreLoadExecutor-37-thread-1] INFO org.apache.solr.core.CoreContainer – registering core: collection1
[junit4] 1> 14638 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4] 1> 14638 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init() done
[junit4] 1> 14640 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.SolrJettyTestBase – Jetty Assigned Port#58881
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\.\solrtest-SolrExampleXMLTest-1374857398878
[junit4] 1> 14660 [TEST-SolrExampleXMLTest.testPingHandler-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPingHandler
[junit4] 1> 14714 [TEST-SolrExampleXMLTest.testPingHandler-seed#[A346D45C556D36E1]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 1> 15986 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onInit: commits: num=1
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_1,generation=1}
[junit4] 1> 15992 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – newest commit generation = 1
[junit4] 1> 16063 [qtp2071342-38] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5ccfdbe0 realtime
[junit4] 1> 16076 [qtp2071342-38] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1441642479280455680)} 0 216
[junit4] 1> 16207 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 16209 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – No uncommitted changes. Skipping IW.commit.
[junit4] 1> 16212 [qtp2071342-39] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@221014ee main
[junit4] 1> 16223 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 16224 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@221014ee main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 1> 16225 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 16233 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@221014ee main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 1> 16239 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 33
[junit4] 1> 16254 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=6
[junit4] 1> 16286 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/ping params={wt=xml&version=2.2} hits=0 status=0 QTime=13
[junit4] 1> 16287 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/ping params={wt=xml&version=2.2} status=0 QTime=14
[junit4] 1> 16290 [TEST-SolrExampleXMLTest.testPingHandler-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPingHandler
[junit4] 1> 16312 [TEST-SolrExampleXMLTest.testQueryWithParams-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testQueryWithParams
[junit4] 1> 16330 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=query&debug=true&wt=xml&version=2.2} hits=0 status=0 QTime=12
[junit4] 1> 16337 [TEST-SolrExampleXMLTest.testQueryWithParams-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testQueryWithParams
[junit4] 1> 16369 [TEST-SolrExampleXMLTest.testAddDelete-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testAddDelete
[junit4] 1> 16378 [qtp2071342-37] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1441642479750217728)} 0 2
[junit4] 1> 16563 [qtp2071342-38] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1441642479779577856)]} 0 165
[junit4] 1> 16571 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 16749 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_1,generation=1}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_2,generation=2}
[junit4] 1> 16749 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – newest commit generation = 2
[junit4] 1> 16851 [qtp2071342-39] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@57c7e0dc main
[junit4] 1> 16852 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 16852 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@57c7e0dc main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4] 1> 16853 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 16863 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@57c7e0dc main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):C1)}
[junit4] 1> 16865 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 294
[junit4] 1> 16915 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=43
[junit4] 1> 16947 [qtp2071342-42] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1441642480346857472)]} 0 2
[junit4] 1> 16959 [qtp2071342-37] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 16961 [qtp2071342-37] INFO org.apache.solr.update.UpdateHandler – No uncommitted changes. Skipping IW.commit.
[junit4] 1> 17048 [qtp2071342-37] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2c9d7ab4 main
[junit4] 1> 17051 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2c9d7ab4 main{StandardDirectoryReader(segments_2:5:nrt)}
[junit4] 1> 17052 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 17051 [qtp2071342-37] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 17059 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2c9d7ab4 main{StandardDirectoryReader(segments_2:5:nrt)}
[junit4] 1> 17062 [qtp2071342-37] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 104
[junit4] 1> 17069 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=1
[junit4] 1> 17078 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1441642480483172352)]} 0 4
[junit4] 1> 17083 [qtp2071342-41] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 17089 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_2,generation=2}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_3,generation=3}
[junit4] 1> 17089 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – newest commit generation = 3
[junit4] 1> 17091 [qtp2071342-41] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@241afe76 main
[junit4] 1> 17094 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@241afe76 main{StandardDirectoryReader(segments_3:7:nrt _1(5.0):C1)}
[junit4] 1> 17094 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 17094 [qtp2071342-41] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 17101 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@241afe76 main{StandardDirectoryReader(segments_3:7:nrt _1(5.0):C1)}
[junit4] 1> 17104 [qtp2071342-41] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 21
[junit4] 1> 17110 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=1
[junit4] 1> 17267 [qtp2071342-38] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@74029d55 realtime
[junit4] 1> 17268 [qtp2071342-38] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=id:"0\ \&\ 222" (-1441642480527212544)} 0 151
[junit4] 1> 17279 [qtp2071342-37] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 17280 [qtp2071342-37] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_3,generation=3}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_4,generation=4}
[junit4] 1> 17280 [qtp2071342-37] INFO org.apache.solr.core.SolrCore – newest commit generation = 4
[junit4] 1> 17283 [qtp2071342-37] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5c075a43 main
[junit4] 1> 17284 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5c075a43 main{StandardDirectoryReader(segments_3:9:nrt)}
[junit4] 1> 17285 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 17292 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5c075a43 main{StandardDirectoryReader(segments_3:9:nrt)}
[junit4] 1> 17304 [qtp2071342-37] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 17305 [qtp2071342-37] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 26
[junit4] 1> 17320 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=1
[junit4] 1> 17328 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1441642480746364928)]} 0 2
[junit4] 1> 17338 [qtp2071342-41] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 & 222 (1441642480756850688)]} 0 3
[junit4] 1> 17347 [qtp2071342-42] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 & 222 (1441642480765239296)]} 0 3
[junit4] 1> 17353 [qtp2071342-38] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 17358 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_4,generation=4}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_5,generation=5}
[junit4] 1> 17358 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – newest commit generation = 5
[junit4] 1> 17360 [qtp2071342-38] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1fb2ae0e main
[junit4] 1> 17361 [qtp2071342-38] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 17361 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1fb2ae0e main{StandardDirectoryReader(segments_5:11:nrt _2(5.0):C3)}
[junit4] 1> 17362 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 17367 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1fb2ae0e main{StandardDirectoryReader(segments_5:11:nrt _2(5.0):C3)}
[junit4] 1> 17369 [qtp2071342-38] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 17
[junit4] 1> 17380 [qtp2071342-37] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=3 status=0 QTime=1
[junit4] 1> 17391 [qtp2071342-40] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1441642480812425216), 1 & 222 (-1441642480813473792), 2 & 222 (-1441642480814522368)]} 0 4
[junit4] 1> 17398 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 17398 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – No uncommitted changes. Skipping IW.commit.
[junit4] 1> 17401 [qtp2071342-39] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@210150d8 main
[junit4] 1> 17403 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 17404 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@210150d8 main{StandardDirectoryReader(segments_5:13:nrt)}
[junit4] 1> 17404 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 17408 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@210150d8 main{StandardDirectoryReader(segments_5:13:nrt)}
[junit4] 1> 17410 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 13
[junit4] 1> 17418 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=2
[junit4] 1> 17420 [TEST-SolrExampleXMLTest.testAddDelete-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAddDelete
[junit4] 1> 17442 [TEST-SolrExampleXMLTest.testStreamingRequest-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testStreamingRequest
[junit4] 1> 17447 [qtp2071342-42] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1441642480871145472)} 0 2
[junit4] 1> 17453 [qtp2071342-38] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 17455 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_5,generation=5}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_6,generation=6}
[junit4] 1> 17455 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – newest commit generation = 6
[junit4] 1> 17457 [qtp2071342-38] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3289a3cb main
[junit4] 1> 17458 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3289a3cb main{StandardDirectoryReader(segments_5:13:nrt)}
[junit4] 1> 17459 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 17458 [qtp2071342-38] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 17465 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3289a3cb main{StandardDirectoryReader(segments_5:13:nrt)}
[junit4] 1> 17467 [qtp2071342-38] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 14
[junit4] 1> 17474 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=1
[junit4] 1> 17533 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 17572 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_6,generation=6}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_7,generation=7}
[junit4] 1> 17572 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – newest commit generation = 7
[junit4] 1> 17629 [qtp2071342-39] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@64795a60 main
[junit4] 1> 17631 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 17631 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@64795a60 main{StandardDirectoryReader(segments_7:15:nrt _3(5.0):C10)}
[junit4] 1> 17632 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 17637 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@64795a60 main{StandardDirectoryReader(segments_7:15:nrt _3(5.0):C10)}
[junit4] 1> 17638 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {add=[0 (1441642480910991360), 1 (1441642480953982976), 2 (1441642480956080128), 3 (1441642480956080129), 4 (1441642480957128704), 5 (1441642480958177280), 6 (1441642480958177281), 7 (1441642480959225856), 8 (1441642480960274432), 9 (1441642480962371584)],commit=} 0 155
[junit4] 1> 17667 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=xml&version=2.2} hits=10 status=0 QTime=22
[junit4] 1> 17682 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4] 1> 17690 [TEST-SolrExampleXMLTest.testStreamingRequest-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testStreamingRequest
[junit4] 1> 17707 [TEST-SolrExampleXMLTest.testUpdateMultiValuedField-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateMultiValuedField
[junit4] 1> 17716 [qtp2071342-38] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1441642481152163840)]} 0 3
[junit4] 1> 17722 [qtp2071342-37] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 17731 [qtp2071342-37] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_7,generation=7}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_8,generation=8}
[junit4] 1> 17731 [qtp2071342-37] INFO org.apache.solr.core.SolrCore – newest commit generation = 8
[junit4] 1> 17739 [qtp2071342-37] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7b9adf67 main
[junit4] 1> 17743 [qtp2071342-37] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 17744 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@7b9adf67 main{StandardDirectoryReader(segments_8:17:nrt _3(5.0):C10 _4(5.0):C1)}
[junit4] 1> 17745 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 17756 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@7b9adf67 main{StandardDirectoryReader(segments_8:17:nrt _3(5.0):C10 _4(5.0):C1)}
[junit4] 1> 17758 [qtp2071342-37] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 36
[junit4] 1> 17781 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:123&wt=xml&version=2.2} hits=1 status=0 QTime=2
[junit4] 1> 17850 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1441642481284284416)]} 0 59
[junit4] 1> 17859 [qtp2071342-41] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 17868 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_8,generation=8}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_9,generation=9}
[junit4] 1> 17868 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – newest commit generation = 9
[junit4] 1> 17872 [qtp2071342-41] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@78710a9 main
[junit4] 1> 17877 [qtp2071342-41] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 17878 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@78710a9 main{StandardDirectoryReader(segments_9:21:nrt _3(5.0):C10 _5(5.0):C1)}
[junit4] 1> 17879 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 17886 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@78710a9 main{StandardDirectoryReader(segments_9:21:nrt _3(5.0):C10 _5(5.0):C1)}
[junit4] 1> 17889 [qtp2071342-41] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 30
[junit4] 1> 17901 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:123&wt=xml&version=2.2} hits=1 status=0 QTime=2
[junit4] 1> 17915 [qtp2071342-37] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1441642481360830464)]} 0 4
[junit4] 1> 17923 [qtp2071342-40] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 17932 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_9,generation=9}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_a,generation=10}
[junit4] 1> 17933 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – newest commit generation = 10
[junit4] 1> 17936 [qtp2071342-40] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@275a24dc main
[junit4] 1> 17938 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@275a24dc main{StandardDirectoryReader(segments_a:25:nrt _3(5.0):C10 _6(5.0):C1)}
[junit4] 1> 17939 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 17945 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@275a24dc main{StandardDirectoryReader(segments_a:25:nrt _3(5.0):C10 _6(5.0):C1)}
[junit4] 1> 17950 [qtp2071342-40] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 17952 [qtp2071342-40] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 29
[junit4] 1> 17961 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:123&wt=xml&version=2.2} hits=1 status=0 QTime=2
[junit4] 1> 17967 [TEST-SolrExampleXMLTest.testUpdateMultiValuedField-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUpdateMultiValuedField
[junit4] 1> 17996 [TEST-SolrExampleXMLTest.testLukeHandler-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testLukeHandler
[junit4] 1> 18004 [qtp2071342-41] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4d7be9e9 realtime
[junit4] 1> 18006 [qtp2071342-41] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1441642481454153728)} 0 6
[junit4] 1> 18017 [qtp2071342-38] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID0 (1441642481467785216)]} 0 4
[junit4] 1> 18031 [qtp2071342-42] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID1 (1441642481483513856)]} 0 2
[junit4] 1> 18040 [qtp2071342-37] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID2 (1441642481493999616)]} 0 1
[junit4] 1> 18061 [qtp2071342-40] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID3 (1441642481516019712)]} 0 2
[junit4] 1> 18070 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID4 (1441642481525456896)]} 0 1
[junit4] 1> 18078 [qtp2071342-41] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 18083 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_a,generation=10}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_b,generation=11}
[junit4] 1> 18084 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – newest commit generation = 11
[junit4] 1> 18086 [qtp2071342-41] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3878f3f0 main
[junit4] 1> 18088 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3878f3f0 main{StandardDirectoryReader(segments_b:29:nrt _7(5.0):C5)}
[junit4] 1> 18089 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 18092 [qtp2071342-41] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 18094 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3878f3f0 main{StandardDirectoryReader(segments_b:29:nrt _7(5.0):C5)}
[junit4] 1> 18107 [qtp2071342-41] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 30
[junit4] 1> 18113 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=5 status=0 QTime=1
[junit4] 1> 18152 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/luke params={wt=xml&version=2.2} status=0 QTime=18
[junit4] 1> 18175 [qtp2071342-37] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=xml&version=2.2} status=0 QTime=4
[junit4] 1> 18515 [TEST-SolrExampleXMLTest.testLukeHandler-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testLukeHandler
[junit4] 1> 18538 [TEST-SolrExampleXMLTest.testRealtimeGet-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testRealtimeGet
[junit4] 1> 18546 [qtp2071342-39] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@68d676d2 realtime
[junit4] 1> 18547 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1441642482022481920)} 0 5
[junit4] 1> 18556 [qtp2071342-41] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[DOCID (1441642482032967680)]} 0 3
[junit4] 1> 18562 [qtp2071342-38] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 18569 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_b,generation=11}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_c,generation=12}
[junit4] 1> 18570 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – newest commit generation = 12
[junit4] 1> 18571 [qtp2071342-38] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@75e21f7e main
[junit4] 1> 18573 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@75e21f7e main{StandardDirectoryReader(segments_c:33:nrt _8(5.0):C1)}
[junit4] 1> 18574 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 18574 [qtp2071342-38] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 18581 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@75e21f7e main{StandardDirectoryReader(segments_c:33:nrt _8(5.0):C1)}
[junit4] 1> 18590 [qtp2071342-38] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 28
[junit4] 1> 18598 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=javabin&qt=/get&version=2} status=0 QTime=2
[junit4] 1> 18604 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=xml&qt=/get&version=2.2} status=0 QTime=0
[junit4] 1> 18607 [TEST-SolrExampleXMLTest.testRealtimeGet-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testRealtimeGet
[junit4] 1> 18622 [TEST-SolrExampleXMLTest.testAddRetrieve-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testAddRetrieve
[junit4] 1> 18629 [qtp2071342-37] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@23ea332b realtime
[junit4] 1> 18630 [qtp2071342-37] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1441642482109513728)} 0 4
[junit4] 1> 18647 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1441642482121048064), id2 (1441642482129436672)]} 0 11
[junit4] 1> 18653 [qtp2071342-41] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 18671 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_c,generation=12}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_d,generation=13}
[junit4] 1> 18672 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – newest commit generation = 13
[junit4] 1> 18674 [qtp2071342-41] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6b704dea main
[junit4] 1> 18676 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6b704dea main{StandardDirectoryReader(segments_d:37:nrt _9(5.0):C2)}
[junit4] 1> 18677 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 18677 [qtp2071342-41] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 18682 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6b704dea main{StandardDirectoryReader(segments_d:37:nrt _9(5.0):C2)}
[junit4] 1> 18683 [qtp2071342-41] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 30
[junit4] 1> 18868 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=xml&version=2.2} hits=2 status=0 QTime=174
[junit4] 1> 18896 [qtp2071342-40] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1441642482387386368), id2 (1441642482391580672)]} 0 5
[junit4] 1> 18904 [qtp2071342-37] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 18913 [qtp2071342-37] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_d,generation=13}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_e,generation=14}
[junit4] 1> 18914 [qtp2071342-37] INFO org.apache.solr.core.SolrCore – newest commit generation = 14
[junit4] 1> 18917 [qtp2071342-37] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@77d25c98 main
[junit4] 1> 18917 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@77d25c98 main{StandardDirectoryReader(segments_e:41:nrt _a(5.0):C2)}
[junit4] 1> 18921 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 18922 [qtp2071342-37] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 18925 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@77d25c98 main{StandardDirectoryReader(segments_e:41:nrt _a(5.0):C2)}
[junit4] 1> 18928 [qtp2071342-37] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 25
[junit4] 1> 18935 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=xml&version=2.2} hits=2 status=0 QTime=2
[junit4] 1> 18957 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={sort=price+asc&q=name:h?llo&wt=xml&version=2.2} hits=1 status=0 QTime=11
[junit4] 1> 18966 [TEST-SolrExampleXMLTest.testAddRetrieve-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAddRetrieve
[junit4] 1> 19013 [TEST-SolrExampleXMLTest.testPivotFacets-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPivotFacets
[junit4] 1> 19021 [qtp2071342-38] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@dcad4c8 realtime
[junit4] 1> 19022 [qtp2071342-38] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1441642482521604096)} 0 3
[junit4] 1> 19028 [qtp2071342-42] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 19029 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_e,generation=14}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_f,generation=15}
[junit4] 1> 19029 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – newest commit generation = 15
[junit4] 1> 19029 [qtp2071342-42] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@56a88582 main
[junit4] 1> 19032 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@56a88582 main{StandardDirectoryReader(segments_e:43:nrt)}
[junit4] 1> 19033 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 19034 [qtp2071342-42] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 19042 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@56a88582 main{StandardDirectoryReader(segments_e:43:nrt)}
[junit4] 1> 19044 [qtp2071342-42] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 17
[junit4] 1> 19052 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=1
[junit4] 1> 19106 [qtp2071342-40] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1441642482564595712), 2 (1441642482567741440), 3 (1441642482569838592), 4 (1441642482570887168), 5 (1441642482572984320), 6 (1441642482601295872), 7 (1441642482605490176), 8 (1441642482607587328), 9 (1441642482608635904), 10 (1441642482609684480), ... (12 adds)]} 0 47
[junit4] 1> 19115 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 19124 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_f,generation=15}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_g,generation=16}
[junit4] 1> 19125 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – newest commit generation = 16
[junit4] 1> 19127 [qtp2071342-39] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@9ab43df main
[junit4] 1> 19128 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@9ab43df main{StandardDirectoryReader(segments_g:45:nrt _b(5.0):C12)}
[junit4] 1> 19129 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 19130 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 19134 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@9ab43df main{StandardDirectoryReader(segments_g:45:nrt _b(5.0):C12)}
[junit4] 1> 19135 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 20
[junit4] 1> 19285 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=15,phase1=15,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 1> 19296 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 1> 19308 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet.missing=false&facet=true&facet.mincount=0&q=*:*&wt=xml&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2.2&rows=0} hits=12 status=0 QTime=167
[junit4] 1> 19379 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=xml&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2.2&rows=0} hits=11 status=0 QTime=56
[junit4] 1> 19382 [TEST-SolrExampleXMLTest.testPivotFacets-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPivotFacets
[junit4] 1> 19478 [TEST-SolrExampleXMLTest.testExampleConfig-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testExampleConfig
[junit4] 1> 19485 [qtp2071342-37] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@659a2644 realtime
[junit4] 1> 19486 [qtp2071342-37] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1441642483008143360)} 0 4
[junit4] 1> 19495 [qtp2071342-40] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1112211111 (1441642483018629120)]} 0 3
[junit4] 1> 19502 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 19507 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_g,generation=16}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_h,generation=17}
[junit4] 1> 19508 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – newest commit generation = 17
[junit4] 1> 19510 [qtp2071342-39] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6eb5533e main
[junit4] 1> 19513 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 19513 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6eb5533e main{StandardDirectoryReader(segments_h:49:nrt _c(5.0):C1)}
[junit4] 1> 19514 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 19518 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6eb5533e main{StandardDirectoryReader(segments_h:49:nrt _c(5.0):C1)}
[junit4] 1> 19519 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 17
[junit4] 1> 19530 [qtp2071342-41] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 19687 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_h,generation=17}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_i,generation=18}
[junit4] 1> 19688 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – newest commit generation = 18
[junit4] 1> 19693 [qtp2071342-41] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1bb8bc93 main
[junit4] 1> 19693 [qtp2071342-41] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 19695 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1bb8bc93 main{StandardDirectoryReader(segments_i:51:nrt _d(5.0):C1)}
[junit4] 1> 19695 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 19700 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1bb8bc93 main{StandardDirectoryReader(segments_i:51:nrt _d(5.0):C1)}
[junit4] 1> 19702 [qtp2071342-41] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=xml&version=2.2} {optimize=} 0 172
[junit4] 1> 19712 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=xml&version=2.2} hits=1 status=0 QTime=1
[junit4] 1> 19738 [qtp2071342-42] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 (1441642483268190208), 3 (1441642483271335936), 4 (1441642483273433088), 5 (1441642483274481664)]} 0 7
[junit4] 1> 19744 [qtp2071342-40] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 19752 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_i,generation=18}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_j,generation=19}
[junit4] 1> 19753 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – newest commit generation = 19
[junit4] 1> 19755 [qtp2071342-40] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6058306a main
[junit4] 1> 19757 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6058306a main{StandardDirectoryReader(segments_j:53:nrt _d(5.0):C1 _e(5.0):C4)}
[junit4] 1> 19758 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 19761 [qtp2071342-40] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 19765 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6058306a main{StandardDirectoryReader(segments_j:53:nrt _d(5.0):C1 _e(5.0):C4)}
[junit4] 1> 19767 [qtp2071342-40] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 23
[junit4] 1> 19773 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 19784 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_j,generation=19}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_k,generation=20}
[junit4] 1> 19785 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – newest commit generation = 20
[junit4] 1> 19792 [qtp2071342-39] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2a980e3b main
[junit4] 1> 19793 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 19794 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2a980e3b main{StandardDirectoryReader(segments_k:55:nrt _f(5.0):C5)}
[junit4] 1> 19794 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 19799 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2a980e3b main{StandardDirectoryReader(segments_k:55:nrt _f(5.0):C5)}
[junit4] 1> 19801 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=xml&version=2.2} {optimize=} 0 28
[junit4] 1> 19943 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=xml&version=2.2} hits=5 status=0 QTime=137
[junit4] 1> 19967 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=xml&fq=inStock:true&version=2.2} hits=2 status=0 QTime=2
[junit4] 1> 20000 [qtp2071342-37] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&q=foo&wt=xml&fq={!field+f%3DinStock}true&fq={!term+f%3Dname}hoss&version=2.2} hits=0 status=0 QTime=25
[junit4] 1> 20004 [TEST-SolrExampleXMLTest.testExampleConfig-seed#[A346D45C556D36E1]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 1> 20060 [qtp2071342-39] INFO org.apache.solr.servlet.SolrDispatchFilter – [admin] webapp=null path=/admin/info/system params={wt=javabin&qt=/admin/info/system&version=2} status=0 QTime=7
[junit4] 1> 20070 [TEST-SolrExampleXMLTest.testExampleConfig-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testExampleConfig
[junit4] 1> 20087 [TEST-SolrExampleXMLTest.testCommitWithinOnDelete-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testCommitWithinOnDelete
[junit4] 1> 20099 [qtp2071342-41] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3e3ed1ae realtime
[junit4] 1> 20100 [qtp2071342-41] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1441642483645677568)} 0 9
[junit4] 1> 20106 [qtp2071342-38] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 20106 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_k,generation=20}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_l,generation=21}
[junit4] 1> 20107 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – newest commit generation = 21
[junit4] 1> 20108 [qtp2071342-38] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@770f0a91 main
[junit4] 1> 20110 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@770f0a91 main{StandardDirectoryReader(segments_k:57:nrt)}
[junit4] 1> 20111 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 20112 [qtp2071342-38] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 20116 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@770f0a91 main{StandardDirectoryReader(segments_k:57:nrt)}
[junit4] 1> 20117 [qtp2071342-38] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4] 1> 20123 [qtp2071342-37] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=1
[junit4] 1> 20132 [qtp2071342-40] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id3 (1441642483685523456)]} 0 5
[junit4] 1> 20137 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 20143 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_l,generation=21}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_m,generation=22}
[junit4] 1> 20143 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – newest commit generation = 22
[junit4] 1> 20145 [qtp2071342-39] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@395e096 main
[junit4] 1> 20147 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@395e096 main{StandardDirectoryReader(segments_m:59:nrt _g(5.0):C1)}
[junit4] 1> 20147 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 20150 [qtp2071342-39] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 20153 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@395e096 main{StandardDirectoryReader(segments_m:59:nrt _g(5.0):C1)}
[junit4] 1> 20154 [qtp2071342-39] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 17
[junit4] 1> 20160 [qtp2071342-41] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=1 status=0 QTime=1
[junit4] 1> 20167 [qtp2071342-38] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[id3 (-1441642483723272192)]} 0 2
[junit4] 1> 20175 [qtp2071342-37] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=1 status=0 QTime=1
[junit4] 1> 20431 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=1 status=0 QTime=0
[junit4] 1> 20688 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=1 status=0 QTime=2
[junit4] 1> 20945 [qtp2071342-41] INFO org.apache.solr.core.SolrCo
[...truncated too long message...]
55
[junit4] 1> 226118 [qtp2071342-37] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@279424c3 main
[junit4] 1> 226120 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@279424c3 main{StandardDirectoryReader(segments_1i:147:nrt)}
[junit4] 1> 226121 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 226121 [qtp2071342-37] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 226125 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@279424c3 main{StandardDirectoryReader(segments_1i:147:nrt)}
[junit4] 1> 226127 [qtp2071342-37] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 16
[junit4] 1> 226134 [qtp2071342-39] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4] 1> 226157 [qtp2071342-41] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1441642699706859520), 2 (1441642699710005248), 3 (1441642699712102400), 4 (1441642699713150976), 5 (1441642699715248128), 6 (1441642699715248129), 7 (1441642699716296704), 8 (1441642699717345280), 9 (1441642699717345281), 10 (1441642699720491008), ... (12 adds)]} 0 15
[junit4] 1> 226164 [qtp2071342-42] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 226172 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_1j,generation=55}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@59559380 lockFactory=org.apache.lucene.store.NativeFSLockFactory@57504a38),segFN=segments_1k,generation=56}
[junit4] 1> 226173 [qtp2071342-42] INFO org.apache.solr.core.SolrCore – newest commit generation = 56
[junit4] 1> 226176 [qtp2071342-42] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@53110fa3 main
[junit4] 1> 226177 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@53110fa3 main{StandardDirectoryReader(segments_1k:149:nrt _14(5.0):C12)}
[junit4] 1> 226185 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4] 1> 226189 [searcherExecutor-38-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@53110fa3 main{StandardDirectoryReader(segments_1k:149:nrt _14(5.0):C12)}
[junit4] 1> 226191 [qtp2071342-42] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4] 1> 226192 [qtp2071342-42] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 28
[junit4] 1> 226202 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 1> 226214 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 1> 226218 [qtp2071342-40] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet.missing=true&facet=true&facet.mincount=0&q=*:*&wt=xml&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2.2&rows=0} hits=12 status=0 QTime=17
[junit4] 1> 226232 [qtp2071342-38] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=xml&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2.2&rows=0} hits=11 status=0 QTime=5
[junit4] 1> 226236 [TEST-SolrExampleXMLTest.testPivotFacetsMissing-seed#[A346D45C556D36E1]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPivotFacetsMissing
[junit4] 1> 226252 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.CoreContainer – Shutting down CoreContainer instance=1162884891
[junit4] 1> 226253 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.SolrCore – [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7f9d6a98
[junit4] 1> 226298 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.update.UpdateHandler – closing DirectUpdateHandler2{commits=60,autocommit maxTime=15000ms,autocommits=1,soft autocommits=3,optimizes=2,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=130,cumulative_deletesById=5,cumulative_deletesByQuery=28,cumulative_errors=1}
[junit4] 1> 226300 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.update.SolrCoreState – Closing SolrCoreState
[junit4] 1> 226300 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.update.DefaultSolrCoreState – SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 1> 226300 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.update.DefaultSolrCoreState – closing IndexWriter with IndexWriterCloser
[junit4] 1> 226308 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.SolrCore – [collection1] Closing main searcher on request.
[junit4] 1> 226309 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 1> 226309 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleXMLTest-1374857398878\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleXMLTest-1374857398878\index;done=false>>]
[junit4] 1> 226311 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleXMLTest-1374857398878\index
[junit4] 1> 226311 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleXMLTest-1374857398878\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleXMLTest-1374857398878\;done=false>>]
[junit4] 1> 226312 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleXMLTest-1374857398878\
[junit4] 1> 226314 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.eclipse.jetty.server.handler.ContextHandler – stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 1> 226374 [SUITE-SolrExampleXMLTest-seed#[A346D45C556D36E1]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene42: {}, docValues:{}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=ja_JP, timezone=Etc/GMT+2
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=2,threads=1,free=10797112,total=34865152
[junit4] 2> NOTE: All tests run in this JVM: [QueryResponseTest, TestCoreAdmin, SolrExampleXMLTest]
[junit4] Completed in 217.81s, 26 tests, 1 error <<< FAILURES!
[...truncated 140 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:389: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:369: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:194: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:449: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1230: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:873: There were test failures: 46 suites, 272 tests, 1 error, 1 ignored (1 assumption)
Total time: 67 minutes 13 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b99 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure