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/04/28 21:26:39 UTC

[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.7.0_21) - Build # 5337 - Failure!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/5337/
Java: 32bit/jdk1.7.0_21 -server -XX:+UseParallelGC

1 tests failed.
REGRESSION:  org.apache.solr.client.solrj.SolrExampleXMLTest.testAddDelete

Error Message:
IOException occured when talking to server at: https://127.0.0.1:48715/solr/collection1

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:48715/solr/collection1
	at __randomizedtesting.SeedInfo.seed([5E6DB6DEB79D5254:968DCBD446358182]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
	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.add(SolrServer.java:116)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
	at org.apache.solr.client.solrj.SolrExampleTests.testAddDelete(SolrExampleTests.java:743)
	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:601)
	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:722)
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 10393 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.SolrExampleXMLTest
[junit4:junit4]   1> 0    [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.SolrJettyTestBase  – Randomized ssl (true) and clientAuth (false)
[junit4:junit4]   1> 8    [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.SolrTestCaseJ4  – ####initCore
[junit4:junit4]   1> 9    [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.SolrTestCaseJ4  – ####initCore end
[junit4:junit4]   1> 157  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.eclipse.jetty.server.Server  – jetty-8.1.10.v20130312
[junit4:junit4]   1> 495  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-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:junit4]   1> 526  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.eclipse.jetty.server.AbstractConnector  – Started SslSelectChannelConnector@127.0.0.1:48715
[junit4:junit4]   1> 557  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  – SolrDispatchFilter.init()
[junit4:junit4]   1> 603  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrResourceLoader  – JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> 604  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrResourceLoader  – using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4:junit4]   1> 624  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.CoreContainer  – looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/solr.xml
[junit4:junit4]   1> 635  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.CoreContainer  – New CoreContainer 5633964
[junit4:junit4]   1> 636  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.CoreContainer  – Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/'
[junit4:junit4]   1> 638  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrResourceLoader  – new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/'
[junit4:junit4]   1> 943  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4:junit4]   1> 945  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Discovered properties file /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/core.properties, adding to cores
[junit4:junit4]   1> SET INST DIR:/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1
[junit4:junit4]   1> 947  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin
[junit4:junit4]   1> 950  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn
[junit4:junit4]   1> 951  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/prop-base
[junit4:junit4]   1> 952  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/tmp
[junit4:junit4]   1> 953  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/tmp/prop-base
[junit4:junit4]   1> 954  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/tmp/text-base
[junit4:junit4]   1> 955  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/tmp/props
[junit4:junit4]   1> 956  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/text-base
[junit4:junit4]   1> 957  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/props
[junit4:junit4]   1> 958  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn
[junit4:junit4]   1> 959  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/prop-base
[junit4:junit4]   1> 961  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/tmp
[junit4:junit4]   1> 962  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/tmp/prop-base
[junit4:junit4]   1> 963  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/tmp/text-base
[junit4:junit4]   1> 964  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/tmp/props
[junit4:junit4]   1> 965  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/text-base
[junit4:junit4]   1> 967  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/props
[junit4:junit4]   1> 976  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting socketTimeout to: 0
[junit4:junit4]   1> 977  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting urlScheme to: http://
[junit4:junit4]   1> 978  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting connTimeout to: 0
[junit4:junit4]   1> 978  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> 979  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting corePoolSize to: 0
[junit4:junit4]   1> 980  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> 981  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> 981  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting sizeOfQueue to: -1
[junit4:junit4]   1> 982  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  – Setting fairnessPolicy to: false
[junit4:junit4]   1> 995  [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-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:junit4]   1> 1085 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.CoreContainer  – Registering Log Listener
[junit4:junit4]   1> 1115 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.CoreContainer  – Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1
[junit4:junit4]   1> 1116 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrResourceLoader  – new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/'
[junit4:junit4]   1> 1203 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrConfig  – Adding specified lib dirs to ClassLoader
[junit4:junit4]   1> 1204 [coreLoadExecutor-3-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  – No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4:junit4]   1> 1205 [coreLoadExecutor-3-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 1206 [coreLoadExecutor-3-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  – No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4:junit4]   1> 1206 [coreLoadExecutor-3-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 1207 [coreLoadExecutor-3-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  – No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4:junit4]   1> 1208 [coreLoadExecutor-3-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 1208 [coreLoadExecutor-3-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  – No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4:junit4]   1> 1209 [coreLoadExecutor-3-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 1209 [coreLoadExecutor-3-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  – Can't find (or read) directory to add to classloader: /non/existent/dir/yields/warning (resolved as: /non/existent/dir/yields/warning).
[junit4:junit4]   1> 1381 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrConfig  – Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> 1514 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.Config  – Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> 1524 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.schema.IndexSchema  – Reading Solr Schema from schema.xml
[junit4:junit4]   1> 1574 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.schema.IndexSchema  – Schema name=example
[junit4:junit4]   1> 2490 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.schema.IndexSchema  – unique key field: id
[junit4:junit4]   1> 2659 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.schema.FileExchangeRateProvider  – Reloading exchange rates from file currency.xml
[junit4:junit4]   1> 2668 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.schema.FileExchangeRateProvider  – Reloading exchange rates from file currency.xml
[junit4:junit4]   1> 2819 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> 2827 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930/
[junit4:junit4]   1> 2829 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.JmxMonitoredMap  – JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@135701c
[junit4:junit4]   1> 2890 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   1> 2891 [coreLoadExecutor-3-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:junit4]   1> 2898 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> 2923 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.CachingDirectoryFactory  – return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930
[junit4:junit4]   1> 2923 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930/index/
[junit4:junit4]   1> 2924 [coreLoadExecutor-3-thread-1] WARN  org.apache.solr.core.SolrCore  – [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930/index' doesn't exist. Creating new index...
[junit4:junit4]   1> 2931 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.CachingDirectoryFactory  – return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930/index
[junit4:junit4]   1> 3019 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1,generation=1}
[junit4:junit4]   1> 3020 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 1
[junit4:junit4]   1> 3027 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – created json: solr.JSONResponseWriter
[junit4:junit4]   1> 3027 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4]   1> 3029 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – created velocity: solr.VelocityResponseWriter
[junit4:junit4]   1> 3036 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – created xslt: solr.XSLTResponseWriter
[junit4:junit4]   1> 3037 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.response.XSLTResponseWriter  – xsltCacheLifetimeSeconds=5
[junit4:junit4]   1> 3283 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /select: solr.SearchHandler
[junit4:junit4]   1> 3285 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /query: solr.SearchHandler
[junit4:junit4]   1> 3289 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> 3291 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /browse: solr.SearchHandler
[junit4:junit4]   1> 3298 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> 3303 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4]   1> 3307 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /update/csv: solr.CSVRequestHandler
[junit4:junit4]   1> 3308 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   1> 3309 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   1> 3309 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4]   1> 3310 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4]   1> 3311 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   1> 3311 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   1> 3316 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> 3321 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /admin/ping: solr.PingRequestHandler
[junit4:junit4]   1> 3326 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   1> 3339 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /replication: solr.ReplicationHandler
[junit4:junit4]   1> 3340 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 3341 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /spell: solr.SearchHandler
[junit4:junit4]   1> 3341 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 3342 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /tvrh: solr.SearchHandler
[junit4:junit4]   1> 3342 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 3343 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /terms: solr.SearchHandler
[junit4:junit4]   1> 3343 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 3344 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.RequestHandlers  – created /elevate: solr.SearchHandler
[junit4:junit4]   1> 3373 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  – xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 3378 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  – xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 3380 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  – xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 3397 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1c503d6 main
[junit4:junit4]   1> 3415 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.update.UpdateHandler  – Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930/tlog
[junit4:junit4]   1> 3423 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – Hard AutoCommit: if uncommited for 15000ms; 
[junit4:junit4]   1> 3424 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.SolrCore  – Soft AutoCommit: disabled
[junit4:junit4]   1> 3425 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  – Initializing spell checkers
[junit4:junit4]   1> 3441 [coreLoadExecutor-3-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:junit4]   1> 3457 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  – No queryConverter defined, using default converter
[junit4:junit4]   1> 3461 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.handler.component.QueryElevationComponent  – Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/conf/elevate.xml
[junit4:junit4]   1> 3526 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.handler.ReplicationHandler  – Commits will be reserved for  10000
[junit4:junit4]   1> 3527 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1c503d6 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> 3609 [searcherExecutor-4-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=76 
[junit4:junit4]   1> 3610 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 3611 [searcherExecutor-4-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  – Loading spell index for spellchecker: default
[junit4:junit4]   1> 3611 [searcherExecutor-4-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  – Loading spell index for spellchecker: wordbreak
[junit4:junit4]   1> 3616 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1c503d6 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> 3621 [coreLoadExecutor-3-thread-1] INFO  org.apache.solr.core.CoreContainer  – registering core: collection1
[junit4:junit4]   1> 3623 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  – user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0
[junit4:junit4]   1> 3624 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  – SolrDispatchFilter.init() done
[junit4:junit4]   1> 3625 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.SolrJettyTestBase  – Jetty Assigned Port#48715
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/./solrtest-SolrExampleXMLTest-1367176927930
[junit4:junit4]   1> 3646 [TEST-SolrExampleXMLTest.testUnicode-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testUnicode
[junit4:junit4]   1> 3668 [TEST-SolrExampleXMLTest.testUnicode-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.client.solrj.impl.HttpClientUtil  – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> 4301 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1,generation=1}
[junit4:junit4]   1> 4303 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 1
[junit4:junit4]   1> 4321 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1f921a5 realtime
[junit4:junit4]   1> 4324 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433588918870081536)} 0 101
[junit4:junit4]   1> 4422 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1433588918940336128), 1 (1433588918995910656), 2 (1433588918995910657), 3 (1433588918996959232), 4 (1433588918998007808), 5 (1433588918999056384), 6 (1433588918999056385), 7 (1433588919000104960), 8 (1433588919000104961), 9 (1433588919000104962), ... (24 adds)]} 0 70
[junit4:junit4]   1> 4486 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 4565 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1,generation=1}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_2,generation=2}
[junit4:junit4]   1> 4566 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 2
[junit4:junit4]   1> 4605 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1470e44 main
[junit4:junit4]   1> 4608 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 4609 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1470e44 main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C24)}
[junit4:junit4]   1> 4610 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 4616 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1470e44 main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C24)}
[junit4:junit4]   1> 4619 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 133
[junit4:junit4]   1> 4647 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=24} hits=24 status=0 QTime=12 
[junit4:junit4]   1> 4715 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@267a73 realtime
[junit4:junit4]   1> 4716 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433588919270637568)} 0 44
[junit4:junit4]   1> 4774 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1433588919353475072), 1 (1433588919355572224), 2 (1433588919357669376), 3 (1433588919358717952), 4 (1433588919359766528), 5 (1433588919360815104), 6 (1433588919361863680), 7 (1433588919362912256), 8 (1433588919363960832), 9 (1433588919366057984), ... (19 adds)]} 0 26
[junit4:junit4]   1> 4792 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 4804 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_2,generation=2}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_3,generation=3}
[junit4:junit4]   1> 4805 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 3
[junit4:junit4]   1> 4806 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@13d3e1d main
[junit4:junit4]   1> 4807 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 4807 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@13d3e1d main{StandardDirectoryReader(segments_3:7:nrt _1(4.4):C19)}
[junit4:junit4]   1> 4809 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 4819 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@13d3e1d main{StandardDirectoryReader(segments_3:7:nrt _1(4.4):C19)}
[junit4:junit4]   1> 4824 [qtp9348246-17] 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:junit4]   1> 4831 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=19} hits=19 status=0 QTime=1 
[junit4:junit4]   1> 4854 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1c6408d realtime
[junit4:junit4]   1> 4855 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433588919460429824)} 0 2
[junit4:junit4]   1> 4888 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1433588919475109888), 1 (1433588919476158464), 2 (1433588919477207040), 3 (1433588919477207041), 4 (1433588919478255616), 5 (1433588919479304192), 6 (1433588919479304193), 7 (1433588919480352768), 8 (1433588919481401344), 9 (1433588919481401345), ... (24 adds)]} 0 23
[junit4:junit4]   1> 4901 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 4917 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_3,generation=3}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_4,generation=4}
[junit4:junit4]   1> 4919 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 4
[junit4:junit4]   1> 4921 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@2b1ca9 main
[junit4:junit4]   1> 4923 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 4923 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@2b1ca9 main{StandardDirectoryReader(segments_4:11:nrt _2(4.4):C24)}
[junit4:junit4]   1> 4926 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 4937 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@2b1ca9 main{StandardDirectoryReader(segments_4:11:nrt _2(4.4):C24)}
[junit4:junit4]   1> 4941 [qtp9348246-14] 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 40
[junit4:junit4]   1> 4947 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=24} hits=24 status=0 QTime=1 
[junit4:junit4]   1> 4969 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@4f4eff realtime
[junit4:junit4]   1> 4970 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433588919581016064)} 0 3
[junit4:junit4]   1> 4990 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1433588919590453248), 1 (1433588919591501824), 2 (1433588919592550400), 3 (1433588919593598976), 4 (1433588919593598977), 5 (1433588919594647552), 6 (1433588919595696128), 7 (1433588919596744704), 8 (1433588919596744705), 9 (1433588919597793280), ... (21 adds)]} 0 13
[junit4:junit4]   1> 5002 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5009 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_4,generation=4}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_5,generation=5}
[junit4:junit4]   1> 5009 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 5
[junit4:junit4]   1> 5011 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@917c5e main
[junit4:junit4]   1> 5012 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5012 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@917c5e main{StandardDirectoryReader(segments_5:15:nrt _3(4.4):C21)}
[junit4:junit4]   1> 5013 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5023 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@917c5e main{StandardDirectoryReader(segments_5:15:nrt _3(4.4):C21)}
[junit4:junit4]   1> 5026 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 24
[junit4:junit4]   1> 5033 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=21} hits=21 status=0 QTime=1 
[junit4:junit4]   1> 5047 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@bc473e realtime
[junit4:junit4]   1> 5047 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433588919661756416)} 0 2
[junit4:junit4]   1> 5055 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1433588919669096448), 1 (1433588919670145024), 2 (1433588919671193600), 3 (1433588919671193601)]} 0 3
[junit4:junit4]   1> 5062 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5066 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_5,generation=5}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_6,generation=6}
[junit4:junit4]   1> 5066 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 6
[junit4:junit4]   1> 5068 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1607aaf main
[junit4:junit4]   1> 5069 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5069 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1607aaf main{StandardDirectoryReader(segments_6:19:nrt _4(4.4):C4)}
[junit4:junit4]   1> 5070 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5074 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1607aaf main{StandardDirectoryReader(segments_6:19:nrt _4(4.4):C4)}
[junit4:junit4]   1> 5076 [qtp9348246-14] 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:junit4]   1> 5081 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=4} hits=4 status=0 QTime=1 
[junit4:junit4]   1> 5087 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@709586 realtime
[junit4:junit4]   1> 5088 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433588919704748032)} 0 2
[junit4:junit4]   1> 5099 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1433588919713136640), 1 (1433588919714185216), 2 (1433588919714185217), 3 (1433588919715233792), 4 (1433588919715233793), 5 (1433588919716282368), 6 (1433588919716282369), 7 (1433588919717330944), 8 (1433588919718379520)]} 0 5
[junit4:junit4]   1> 5108 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5118 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_6,generation=6}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_7,generation=7}
[junit4:junit4]   1> 5120 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 7
[junit4:junit4]   1> 5122 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@354c20 main
[junit4:junit4]   1> 5124 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5124 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@354c20 main{StandardDirectoryReader(segments_7:23:nrt _5(4.4):C9)}
[junit4:junit4]   1> 5125 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5131 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@354c20 main{StandardDirectoryReader(segments_7:23:nrt _5(4.4):C9)}
[junit4:junit4]   1> 5133 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 25
[junit4:junit4]   1> 5139 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=9} hits=9 status=0 QTime=1 
[junit4:junit4]   1> 5147 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@6dd984 realtime
[junit4:junit4]   1> 5149 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433588919767662592)} 0 3
[junit4:junit4]   1> 5172 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1433588919779196928), 1 (1433588919780245504), 2 (1433588919781294080), 3 (1433588919781294081), 4 (1433588919782342656), 5 (1433588919782342657), 6 (1433588919783391232), 7 (1433588919783391233), 8 (1433588919784439808), 9 (1433588919784439809), ... (25 adds)]} 0 15
[junit4:junit4]   1> 5180 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5192 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_7,generation=7}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_8,generation=8}
[junit4:junit4]   1> 5192 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 8
[junit4:junit4]   1> 5193 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@18449a7 main
[junit4:junit4]   1> 5195 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@18449a7 main{StandardDirectoryReader(segments_8:27:nrt _6(4.4):C25)}
[junit4:junit4]   1> 5195 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5196 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5200 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@18449a7 main{StandardDirectoryReader(segments_8:27:nrt _6(4.4):C25)}
[junit4:junit4]   1> 5201 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 22
[junit4:junit4]   1> 5206 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=25} hits=25 status=0 QTime=1 
[junit4:junit4]   1> 5215 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@77c9e2 realtime
[junit4:junit4]   1> 5215 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433588919837917184)} 0 2
[junit4:junit4]   1> 5238 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1433588919849451520), 1 (1433588919850500096), 2 (1433588919851548672), 3 (1433588919851548673), 4 (1433588919852597248), 5 (1433588919852597249), 6 (1433588919853645824), 7 (1433588919853645825), 8 (1433588919854694400), 9 (1433588919854694401), ... (28 adds)]} 0 14
[junit4:junit4]   1> 5245 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5256 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_8,generation=8}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_9,generation=9}
[junit4:junit4]   1> 5257 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 9
[junit4:junit4]   1> 5259 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@ff73af main
[junit4:junit4]   1> 5261 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@ff73af main{StandardDirectoryReader(segments_9:31:nrt _7(4.4):C28)}
[junit4:junit4]   1> 5261 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5261 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5266 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@ff73af main{StandardDirectoryReader(segments_9:31:nrt _7(4.4):C28)}
[junit4:junit4]   1> 5268 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 23
[junit4:junit4]   1> 5273 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=28} hits=28 status=0 QTime=1 
[junit4:junit4]   1> 5286 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@eb625c realtime
[junit4:junit4]   1> 5287 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433588919912366080)} 0 3
[junit4:junit4]   1> 5310 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1433588919924948992), 1 (1433588919925997568), 2 (1433588919927046144), 3 (1433588919927046145), 4 (1433588919928094720), 5 (1433588919929143296), 6 (1433588919929143297), 7 (1433588919930191872), 8 (1433588919931240448), 9 (1433588919931240449), ... (20 adds)]} 0 14
[junit4:junit4]   1> 5319 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5326 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_9,generation=9}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_a,generation=10}
[junit4:junit4]   1> 5327 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 10
[junit4:junit4]   1> 5329 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@127043 main
[junit4:junit4]   1> 5331 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@127043 main{StandardDirectoryReader(segments_a:35:nrt _8(4.4):C20)}
[junit4:junit4]   1> 5331 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5331 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5336 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@127043 main{StandardDirectoryReader(segments_a:35:nrt _8(4.4):C20)}
[junit4:junit4]   1> 5338 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 19
[junit4:junit4]   1> 5343 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=20} hits=20 status=0 QTime=0 
[junit4:junit4]   1> 5354 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1f8ced7 realtime
[junit4:junit4]   1> 5355 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1433588919983669248)} 0 3
[junit4:junit4]   1> 5376 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1433588919995203584), 1 (1433588919996252160), 2 (1433588919997300736), 3 (1433588919997300737), 4 (1433588919998349312), 5 (1433588919999397888), 6 (1433588919999397889), 7 (1433588920000446464), 8 (1433588920000446465), 9 (1433588920001495040), ... (18 adds)]} 0 13
[junit4:junit4]   1> 5384 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5389 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_a,generation=10}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_b,generation=11}
[junit4:junit4]   1> 5390 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 11
[junit4:junit4]   1> 5391 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1d78445 main
[junit4:junit4]   1> 5392 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1d78445 main{StandardDirectoryReader(segments_b:39:nrt _9(4.4):C18)}
[junit4:junit4]   1> 5392 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5392 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5400 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1d78445 main{StandardDirectoryReader(segments_b:39:nrt _9(4.4):C18)}
[junit4:junit4]   1> 5402 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4:junit4]   1> 5406 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=18} hits=18 status=0 QTime=0 
[junit4:junit4]   1> 5413 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1359448 realtime
[junit4:junit4]   1> 5414 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433588920046583808)} 0 2
[junit4:junit4]   1> 5438 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1433588920059166720), 1 (1433588920060215296), 2 (1433588920061263872), 3 (1433588920061263873), 4 (1433588920062312448), 5 (1433588920063361024), 6 (1433588920063361025), 7 (1433588920064409600), 8 (1433588920064409601), 9 (1433588920065458176), ... (26 adds)]} 0 14
[junit4:junit4]   1> 5444 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5449 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_b,generation=11}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_c,generation=12}
[junit4:junit4]   1> 5450 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 12
[junit4:junit4]   1> 5451 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1c41cea main
[junit4:junit4]   1> 5452 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1c41cea main{StandardDirectoryReader(segments_c:43:nrt _a(4.4):C26)}
[junit4:junit4]   1> 5452 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5452 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5456 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1c41cea main{StandardDirectoryReader(segments_c:43:nrt _a(4.4):C26)}
[junit4:junit4]   1> 5457 [qtp9348246-19] 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:junit4]   1> 5463 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=26} hits=26 status=0 QTime=1 
[junit4:junit4]   1> 5478 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@34c76f realtime
[junit4:junit4]   1> 5480 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433588920113692672)} 0 4
[junit4:junit4]   1> 5492 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1433588920124178432), 1 (1433588920125227008), 2 (1433588920126275584), 3 (1433588920126275585), 4 (1433588920127324160), 5 (1433588920128372736), 6 (1433588920128372737), 7 (1433588920129421312), 8 (1433588920130469888)]} 0 6
[junit4:junit4]   1> 5500 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5505 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_c,generation=12}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_d,generation=13}
[junit4:junit4]   1> 5505 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 13
[junit4:junit4]   1> 5507 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@982d8e main
[junit4:junit4]   1> 5508 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5508 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@982d8e main{StandardDirectoryReader(segments_d:47:nrt _b(4.4):C9)}
[junit4:junit4]   1> 5510 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5513 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@982d8e main{StandardDirectoryReader(segments_d:47:nrt _b(4.4):C9)}
[junit4:junit4]   1> 5514 [qtp9348246-19] 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:junit4]   1> 5519 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=9} hits=9 status=0 QTime=1 
[junit4:junit4]   1> 5528 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@77947 realtime
[junit4:junit4]   1> 5529 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433588920166121472)} 0 3
[junit4:junit4]   1> 5539 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1433588920174510080), 1 (1433588920175558656), 2 (1433588920176607232), 3 (1433588920177655808), 4 (1433588920177655809), 5 (1433588920178704384)]} 0 5
[junit4:junit4]   1> 5546 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5550 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_d,generation=13}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_e,generation=14}
[junit4:junit4]   1> 5551 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 14
[junit4:junit4]   1> 5553 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1867d87 main
[junit4:junit4]   1> 5554 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5554 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1867d87 main{StandardDirectoryReader(segments_e:51:nrt _c(4.4):C6)}
[junit4:junit4]   1> 5555 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5558 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1867d87 main{StandardDirectoryReader(segments_e:51:nrt _c(4.4):C6)}
[junit4:junit4]   1> 5559 [qtp9348246-19] 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:junit4]   1> 5564 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=6} hits=6 status=0 QTime=1 
[junit4:junit4]   1> 5568 [TEST-SolrExampleXMLTest.testUnicode-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testUnicode
[junit4:junit4]   1> 5584 [TEST-SolrExampleXMLTest.testFaceting-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testFaceting
[junit4:junit4]   1> 5588 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1da9c84 realtime
[junit4:junit4]   1> 5589 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433588920230084608)} 0 2
[junit4:junit4]   1> 5596 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5596 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_e,generation=14}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_f,generation=15}
[junit4:junit4]   1> 5597 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 15
[junit4:junit4]   1> 5597 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@bff687 main
[junit4:junit4]   1> 5598 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@bff687 main{StandardDirectoryReader(segments_e:53:nrt)}
[junit4:junit4]   1> 5599 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5598 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5602 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@bff687 main{StandardDirectoryReader(segments_e:53:nrt)}
[junit4:junit4]   1> 5603 [qtp9348246-17] 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 8
[junit4:junit4]   1> 5607 [qtp9348246-19] 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:junit4]   1> 5625 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1433588920257347584), 2 (1433588920257347585), 3 (1433588920265736192), 4 (1433588920265736193), 5 (1433588920266784768), 6 (1433588920266784769), 7 (1433588920267833344), 8 (1433588920267833345), 9 (1433588920268881920), 10 (1433588920268881921)]} 0 13
[junit4:junit4]   1> 5631 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5639 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_f,generation=15}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_g,generation=16}
[junit4:junit4]   1> 5640 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 16
[junit4:junit4]   1> 5650 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@526747 main
[junit4:junit4]   1> 5651 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@526747 main{StandardDirectoryReader(segments_g:55:nrt _d(4.4):C10)}
[junit4:junit4]   1> 5651 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5651 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5658 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@526747 main{StandardDirectoryReader(segments_g:55:nrt _d(4.4):C10)}
[junit4:junit4]   1> 5660 [qtp9348246-18] 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:junit4]   1> 5699 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=3,phase1=3,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4:junit4]   1> 5702 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=xml&version=2.2&rows=0} hits=10 status=0 QTime=38 
[junit4:junit4]   1> 5723 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=xml&fq=features:two&version=2.2&rows=0} hits=5 status=0 QTime=10 
[junit4:junit4]   1> 5728 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=4&q=*:*&facet.field=features&wt=xml&fq=features:two&version=2.2&rows=0} hits=5 status=0 QTime=0 
[junit4:junit4]   1> 5733 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=-1&q=*:*&facet.field=features&wt=xml&fq=features:two&version=2.2&rows=0} hits=5 status=0 QTime=0 
[junit4:junit4]   1> 5736 [TEST-SolrExampleXMLTest.testFaceting-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testFaceting
[junit4:junit4]   1> 5750 [TEST-SolrExampleXMLTest.testRealtimeGet-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testRealtimeGet
[junit4:junit4]   1> 5756 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1fed553 realtime
[junit4:junit4]   1> 5757 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433588920405196800)} 0 3
[junit4:junit4]   1> 5764 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[DOCID (1433588920413585408)]} 0 2
[junit4:junit4]   1> 5772 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5776 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_g,generation=16}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_h,generation=17}
[junit4:junit4]   1> 5777 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 17
[junit4:junit4]   1> 5779 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@bb4956 main
[junit4:junit4]   1> 5780 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5780 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@bb4956 main{StandardDirectoryReader(segments_h:59:nrt _e(4.4):C1)}
[junit4:junit4]   1> 5781 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5784 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@bb4956 main{StandardDirectoryReader(segments_h:59:nrt _e(4.4):C1)}
[junit4:junit4]   1> 5785 [qtp9348246-18] 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:junit4]   1> 5791 [qtp9348246-17] 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:junit4]   1> 5795 [qtp9348246-19] 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:junit4]   1> 5797 [TEST-SolrExampleXMLTest.testRealtimeGet-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testRealtimeGet
[junit4:junit4]   1> 5811 [TEST-SolrExampleXMLTest.testStreamingRequest-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testStreamingRequest
[junit4:junit4]   1> 5815 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@194b9d9 realtime
[junit4:junit4]   1> 5816 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433588920468111360)} 0 2
[junit4:junit4]   1> 5822 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5823 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_h,generation=17}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_i,generation=18}
[junit4:junit4]   1> 5823 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 18
[junit4:junit4]   1> 5824 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@16f942c main
[junit4:junit4]   1> 5825 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5825 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@16f942c main{StandardDirectoryReader(segments_h:61:nrt)}
[junit4:junit4]   1> 5826 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5829 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@16f942c main{StandardDirectoryReader(segments_h:61:nrt)}
[junit4:junit4]   1> 5830 [qtp9348246-18] 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 8
[junit4:junit4]   1> 5834 [qtp9348246-17] 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:junit4]   1> 5845 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5848 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_i,generation=18}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_j,generation=19}
[junit4:junit4]   1> 5848 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 19
[junit4:junit4]   1> 5849 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@12b7e7f main
[junit4:junit4]   1> 5850 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@12b7e7f main{StandardDirectoryReader(segments_j:63:nrt _f(4.4):C10)}
[junit4:junit4]   1> 5851 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5850 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5854 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@12b7e7f main{StandardDirectoryReader(segments_j:63:nrt _f(4.4):C10)}
[junit4:junit4]   1> 5855 [qtp9348246-19] 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 (1433588920494325760), 1 (1433588920496422912), 2 (1433588920496422913), 3 (1433588920496422914), 4 (1433588920497471488), 5 (1433588920497471489), 6 (1433588920498520064), 7 (1433588920498520065), 8 (1433588920499568640), 9 (1433588920499568641)],commit=} 0 16
[junit4:junit4]   1> 5863 [qtp9348246-14] 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=4 
[junit4:junit4]   1> 5870 [qtp9348246-18] 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=0 
[junit4:junit4]   1> 5872 [TEST-SolrExampleXMLTest.testStreamingRequest-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testStreamingRequest
[junit4:junit4]   1> 5885 [TEST-SolrExampleXMLTest.testAddDelete-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testAddDelete
[junit4:junit4]   1> 5889 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@91a7b9 realtime
[junit4:junit4]   1> 5889 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433588920545705984)} 0 2
[junit4:junit4]   1> 5895 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1433588920550948864)]} 0 2
[junit4:junit4]   1> 5901 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5903 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_j,generation=19}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_k,generation=20}
[junit4:junit4]   1> 5904 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 20
[junit4:junit4]   1> 5905 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1cf5c86 main
[junit4:junit4]   1> 5906 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5906 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1cf5c86 main{StandardDirectoryReader(segments_k:67:nrt _g(4.4):C1)}
[junit4:junit4]   1> 5907 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5909 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1cf5c86 main{StandardDirectoryReader(segments_k:67:nrt _g(4.4):C1)}
[junit4:junit4]   1> 5910 [qtp9348246-14] 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 9
[junit4:junit4]   1> 5915 [qtp9348246-18] 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:junit4]   1> 5920 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1433588920579260416)]} 0 1
[junit4:junit4]   1> 5927 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5928 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_k,generation=20}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_l,generation=21}
[junit4:junit4]   1> 5929 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 21
[junit4:junit4]   1> 5930 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@8dcb9c main
[junit4:junit4]   1> 5930 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@8dcb9c main{StandardDirectoryReader(segments_l:69:nrt)}
[junit4:junit4]   1> 5931 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5931 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5934 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@8dcb9c main{StandardDirectoryReader(segments_l:69:nrt)}
[junit4:junit4]   1> 5935 [qtp9348246-19] 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 8
[junit4:junit4]   1> 5939 [qtp9348246-14] 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:junit4]   1> 5945 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1433588920604426240)]} 0 1
[junit4:junit4]   1> 5951 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5953 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_l,generation=21}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_m,generation=22}
[junit4:junit4]   1> 5954 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 22
[junit4:junit4]   1> 5955 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1386741 main
[junit4:junit4]   1> 5956 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1386741 main{StandardDirectoryReader(segments_m:71:nrt _h(4.4):C1)}
[junit4:junit4]   1> 5956 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5957 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5960 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1386741 main{StandardDirectoryReader(segments_m:71:nrt _h(4.4):C1)}
[junit4:junit4]   1> 5961 [qtp9348246-17] 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 10
[junit4:junit4]   1> 5965 [qtp9348246-19] 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:junit4]   1> 5971 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@edc3da realtime
[junit4:junit4]   1> 5972 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=id:"0\ \&\ 222" (-1433588920631689216)} 0 2
[junit4:junit4]   1> 5978 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 5979 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_m,generation=22}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_n,generation=23}
[junit4:junit4]   1> 5979 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 23
[junit4:junit4]   1> 5980 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@9ae609 main
[junit4:junit4]   1> 5981 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@9ae609 main{StandardDirectoryReader(segments_m:73:nrt)}
[junit4:junit4]   1> 5981 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 5981 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 5984 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@9ae609 main{StandardDirectoryReader(segments_m:73:nrt)}
[junit4:junit4]   1> 5985 [qtp9348246-18] 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 7
[junit4:junit4]   1> 5990 [qtp9348246-17] 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:junit4]   1> 5997 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1433588920657903616)]} 0 2
[junit4:junit4]   1> 6005 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 & 222 (1433588920666292224)]} 0 3
[junit4:junit4]   1> 20997 [commitScheduler-6-thread-1] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 21001 [commitScheduler-6-thread-1] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_n,generation=23}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_o,generation=24}
[junit4:junit4]   1> 21002 [commitScheduler-6-thread-1] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 24
[junit4:junit4]   1> 21004 [commitScheduler-6-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@12abfb4 realtime
[junit4:junit4]   1> 21005 [commitScheduler-6-thread-1] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 206329 [TEST-SolrExampleXMLTest.testAddDelete-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testAddDelete
[junit4:junit4]   1> 206331 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 & 222 (1433589130723328000)]} 0 3
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleXMLTest -Dtests.method=testAddDelete -Dtests.seed=5E6DB6DEB79D5254 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ko_KR -Dtests.timezone=America/St_Vincent -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR    201s J0 | SolrExampleXMLTest.testAddDelete <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:48715/solr/collection1
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([5E6DB6DEB79D5254:968DCBD446358182]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrExampleTests.testAddDelete(SolrExampleTests.java:743)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]    > Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
[junit4:junit4]    > 	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
[junit4:junit4]    > 	at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
[junit4:junit4]    > 	at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
[junit4:junit4]    > 	at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
[junit4:junit4]    > 	at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
[junit4:junit4]    > 	at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
[junit4:junit4]    > 	at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
[junit4:junit4]    > 	at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
[junit4:junit4]    > 	at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
[junit4:junit4]    > 	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
[junit4:junit4]    > 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4:junit4]    > 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4:junit4]    > 	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
[junit4:junit4]    > 	... 45 more
[junit4:junit4]   1> 206386 [TEST-SolrExampleXMLTest.testUpdateMultiValuedField-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testUpdateMultiValuedField
[junit4:junit4]   1> 206401 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1433589130797776896)]} 0 2
[junit4:junit4]   1> 206409 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 206414 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_o,generation=24}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_p,generation=25}
[junit4:junit4]   1> 206415 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 25
[junit4:junit4]   1> 206419 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1578be8 main
[junit4:junit4]   1> 206421 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1578be8 main{StandardDirectoryReader(segments_p:77:nrt _i(4.4):C2 _j(4.4):C2)}
[junit4:junit4]   1> 206421 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 206421 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 206425 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1578be8 main{StandardDirectoryReader(segments_p:77:nrt _i(4.4):C2 _j(4.4):C2)}
[junit4:junit4]   1> 206427 [qtp9348246-18] 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 18
[junit4:junit4]   1> 206433 [qtp9348246-19] 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:junit4]   1> 206455 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1433589130853351424)]} 0 16
[junit4:junit4]   1> 206465 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 206472 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_p,generation=25}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_q,generation=26}
[junit4:junit4]   1> 206473 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 26
[junit4:junit4]   1> 206477 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@7409f4 main
[junit4:junit4]   1> 206479 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@7409f4 main{StandardDirectoryReader(segments_q:80:nrt _i(4.4):C2 _j(4.4):C2/1 _k(4.4):C1)}
[junit4:junit4]   1> 206480 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 206480 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 206487 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@7409f4 main{StandardDirectoryReader(segments_q:80:nrt _i(4.4):C2 _j(4.4):C2/1 _k(4.4):C1)}
[junit4:junit4]   1> 206488 [qtp9348246-18] 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:junit4]   1> 206495 [qtp9348246-19] 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=1 
[junit4:junit4]   1> 206509 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1433589130908925952)]} 0 5
[junit4:junit4]   1> 206519 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 206524 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_q,generation=26}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_r,generation=27}
[junit4:junit4]   1> 206524 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 27
[junit4:junit4]   1> 206527 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1a7848b main
[junit4:junit4]   1> 206528 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1a7848b main{StandardDirectoryReader(segments_r:84:nrt _i(4.4):C2 _j(4.4):C2/1 _l(4.4):C1)}
[junit4:junit4]   1> 206529 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 206530 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 206536 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1a7848b main{StandardDirectoryReader(segments_r:84:nrt _i(4.4):C2 _j(4.4):C2/1 _l(4.4):C1)}
[junit4:junit4]   1> 206538 [qtp9348246-14] 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 19
[junit4:junit4]   1> 206545 [qtp9348246-18] 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=1 
[junit4:junit4]   1> 206549 [TEST-SolrExampleXMLTest.testUpdateMultiValuedField-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testUpdateMultiValuedField
[junit4:junit4]   1> 206571 [TEST-SolrExampleXMLTest.testUpdateRequestWithParameters-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testUpdateRequestWithParameters
[junit4:junit4]   1> 206572 [TEST-SolrExampleXMLTest.testUpdateRequestWithParameters-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.client.solrj.impl.HttpClientUtil  – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> 206647 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@688085 realtime
[junit4:junit4]   1> 206648 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589131055726592)} 0 2
[junit4:junit4]   1> 206654 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 206654 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_r,generation=27}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_s,generation=28}
[junit4:junit4]   1> 206655 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 28
[junit4:junit4]   1> 206656 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@5d023d main
[junit4:junit4]   1> 206657 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@5d023d main{StandardDirectoryReader(segments_r:86:nrt)}
[junit4:junit4]   1> 206657 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 206657 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 206660 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@5d023d main{StandardDirectoryReader(segments_r:86:nrt)}
[junit4:junit4]   1> 206661 [qtp9348246-18] 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 7
[junit4:junit4]   1> 206666 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={overwrite=false&wt=xml&version=2.2} {add=[id1 (1433589131075649536)]} 0 1
[junit4:junit4]   1> 206671 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={overwrite=false&wt=xml&version=2.2} {add=[id1 (1433589131080892416)]} 0 1
[junit4:junit4]   1> 206676 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 206678 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_s,generation=28}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_t,generation=29}
[junit4:junit4]   1> 206679 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 29
[junit4:junit4]   1> 206680 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1a01c24 main
[junit4:junit4]   1> 206680 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1a01c24 main{StandardDirectoryReader(segments_t:88:nrt _m(4.4):C2)}
[junit4:junit4]   1> 206681 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 206681 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 206683 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1a01c24 main{StandardDirectoryReader(segments_t:88:nrt _m(4.4):C2)}
[junit4:junit4]   1> 206684 [qtp9348246-14] 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 8
[junit4:junit4]   1> 206688 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=2 status=0 QTime=0 
[junit4:junit4]   1> 206690 [TEST-SolrExampleXMLTest.testUpdateRequestWithParameters-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testUpdateRequestWithParameters
[junit4:junit4]   1> 206701 [TEST-SolrExampleXMLTest.testCommitWithinOnDelete-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testCommitWithinOnDelete
[junit4:junit4]   1> 206707 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1f98b94 realtime
[junit4:junit4]   1> 206708 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589131117592576)} 0 4
[junit4:junit4]   1> 206715 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 206716 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_t,generation=29}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_u,generation=30}
[junit4:junit4]   1> 206717 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 30
[junit4:junit4]   1> 206718 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@e7185f main
[junit4:junit4]   1> 206720 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@e7185f main{StandardDirectoryReader(segments_t:90:nrt)}
[junit4:junit4]   1> 206720 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 206720 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 206726 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@e7185f main{StandardDirectoryReader(segments_t:90:nrt)}
[junit4:junit4]   1> 206728 [qtp9348246-17] 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:junit4]   1> 206734 [qtp9348246-14] 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:junit4]   1> 206751 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id3 (1433589131159535616)]} 0 6
[junit4:junit4]   1> 206760 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 206766 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_u,generation=30}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_v,generation=31}
[junit4:junit4]   1> 206767 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 31
[junit4:junit4]   1> 206770 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@968db1 main
[junit4:junit4]   1> 206771 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@968db1 main{StandardDirectoryReader(segments_v:92:nrt _n(4.4):C1)}
[junit4:junit4]   1> 206772 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 206773 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 206778 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@968db1 main{StandardDirectoryReader(segments_v:92:nrt _n(4.4):C1)}
[junit4:junit4]   1> 206779 [qtp9348246-19] 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 19
[junit4:junit4]   1> 206786 [qtp9348246-17] 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:junit4]   1> 206794 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[id3 (-1433589131209867264)]} 0 1
[junit4:junit4]   1> 206800 [qtp9348246-18] 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:junit4]   1> 207057 [qtp9348246-19] 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:junit4]   1> 207314 [qtp9348246-18] 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:junit4]   1> 207570 [qtp9348246-19] 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:junit4]   1> 207794 [commitScheduler-7-thread-1] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4]   1> 207796 [commitScheduler-7-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@df7aa6 main
[junit4:junit4]   1> 207797 [commitScheduler-7-thread-1] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 207797 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@df7aa6 main{StandardDirectoryReader(segments_v:94:nrt)}
[junit4:junit4]   1> 207799 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 207804 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@df7aa6 main{StandardDirectoryReader(segments_v:94:nrt)}
[junit4:junit4]   1> 207828 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=id:id3&wt=xml&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 207831 [TEST-SolrExampleXMLTest.testCommitWithinOnDelete-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testCommitWithinOnDelete
[junit4:junit4]   1> 207854 [TEST-SolrExampleXMLTest.testContentStreamRequest-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testContentStreamRequest
[junit4:junit4]   1> 207859 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589132326600704)} 0 1
[junit4:junit4]   1> 207868 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 207870 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_v,generation=31}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_w,generation=32}
[junit4:junit4]   1> 207871 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 32
[junit4:junit4]   1> 207872 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@111f136 main
[junit4:junit4]   1> 207873 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@111f136 main{StandardDirectoryReader(segments_v:94:nrt)}
[junit4:junit4]   1> 207874 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 207874 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 207880 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@111f136 main{StandardDirectoryReader(segments_v:94:nrt)}
[junit4:junit4]   1> 207881 [qtp9348246-14] 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:junit4]   1> 207888 [qtp9348246-19] 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:junit4]   1> 207926 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 207933 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_w,generation=32}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_x,generation=33}
[junit4:junit4]   1> 207933 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 33
[junit4:junit4]   1> 207935 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1c51dde main
[junit4:junit4]   1> 207936 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1c51dde main{StandardDirectoryReader(segments_x:96:nrt _o(4.4):C10)}
[junit4:junit4]   1> 207936 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 207936 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 207941 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1c51dde main{StandardDirectoryReader(segments_x:96:nrt _o(4.4):C10)}
[junit4:junit4]   1> 207942 [qtp9348246-17] 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=[0553573403 (1433589132383223808), 0553579908 (1433589132385320960), 055357342X (1433589132386369536), 0553293354 (1433589132388466688), 0812521390 (1433589132389515264), 0812550706 (1433589132391612416), 0441385532 (1433589132392660992), 0380014300 (1433589132393709568), 0805080481 (1433589132395806720), 080508049X (1433589132396855296)],commit=} 0 39
[junit4:junit4]   1> 207946 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=10 status=0 QTime=0 
[junit4:junit4]   1> 207952 [TEST-SolrExampleXMLTest.testContentStreamRequest-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testContentStreamRequest
[junit4:junit4]   1> 207963 [TEST-SolrExampleXMLTest.testUpdateField-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testUpdateField
[junit4:junit4]   1> 207967 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@cd3c45 realtime
[junit4:junit4]   1> 207967 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589132439846912)} 0 2
[junit4:junit4]   1> 207972 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 207973 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_x,generation=33}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_y,generation=34}
[junit4:junit4]   1> 207973 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 34
[junit4:junit4]   1> 207974 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@168afc5 main
[junit4:junit4]   1> 207975 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@168afc5 main{StandardDirectoryReader(segments_x:98:nrt)}
[junit4:junit4]   1> 207975 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 207975 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 207978 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@168afc5 main{StandardDirectoryReader(segments_x:98:nrt)}
[junit4:junit4]   1> 207979 [qtp9348246-18] 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 7
[junit4:junit4]   1> 207984 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1433589132457672704)]} 0 2
[junit4:junit4]   1> 207989 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 207991 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_y,generation=34}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_z,generation=35}
[junit4:junit4]   1> 207992 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 35
[junit4:junit4]   1> 207993 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@2d5d96 main
[junit4:junit4]   1> 207994 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@2d5d96 main{StandardDirectoryReader(segments_z:100:nrt _p(4.4):C1)}
[junit4:junit4]   1> 207994 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 207994 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 207997 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@2d5d96 main{StandardDirectoryReader(segments_z:100:nrt _p(4.4):C1)}
[junit4:junit4]   1> 207998 [qtp9348246-19] 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 9
[junit4:junit4]   1> 208002 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   1> 208007 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 1
[junit4:junit4]   1> 208012 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1433589132487032832)]} 0 1
[junit4:junit4]   1> 208017 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208020 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_z,generation=35}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_10,generation=36}
[junit4:junit4]   1> 208021 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 36
[junit4:junit4]   1> 208022 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@c847a7 main
[junit4:junit4]   1> 208023 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@c847a7 main{StandardDirectoryReader(segments_10:104:nrt _q(4.4):C1)}
[junit4:junit4]   1> 208023 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208023 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208026 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@c847a7 main{StandardDirectoryReader(segments_10:104:nrt _q(4.4):C1)}
[junit4:junit4]   1> 208027 [qtp9348246-19] 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 10
[junit4:junit4]   1> 208031 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   1> 208036 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1433589132512198656)]} 0 1
[junit4:junit4]   1> 208041 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208044 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_10,generation=36}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_11,generation=37}
[junit4:junit4]   1> 208045 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 37
[junit4:junit4]   1> 208046 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@e2cc7 main
[junit4:junit4]   1> 208047 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@e2cc7 main{StandardDirectoryReader(segments_11:108:nrt _r(4.4):C1)}
[junit4:junit4]   1> 208047 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208047 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208050 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@e2cc7 main{StandardDirectoryReader(segments_11:108:nrt _r(4.4):C1)}
[junit4:junit4]   1> 208051 [qtp9348246-14] 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 10
[junit4:junit4]   1> 208056 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   1> 208059 [TEST-SolrExampleXMLTest.testUpdateField-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testUpdateField
[junit4:junit4]   1> 208082 [TEST-SolrExampleXMLTest.testPivotFacetsMissing-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testPivotFacetsMissing
[junit4:junit4]   1> 208088 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@b645b9 realtime
[junit4:junit4]   1> 208089 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589132565676032)} 0 3
[junit4:junit4]   1> 208098 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208099 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_11,generation=37}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_12,generation=38}
[junit4:junit4]   1> 208100 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 38
[junit4:junit4]   1> 208102 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@528c99 main
[junit4:junit4]   1> 208103 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@528c99 main{StandardDirectoryReader(segments_11:110:nrt)}
[junit4:junit4]   1> 208103 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208103 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208107 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@528c99 main{StandardDirectoryReader(segments_11:110:nrt)}
[junit4:junit4]   1> 208107 [qtp9348246-18] 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 9
[junit4:junit4]   1> 208111 [qtp9348246-14] 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:junit4]   1> 208124 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1433589132598181888), 2 (1433589132599230464), 3 (1433589132600279040), 4 (1433589132600279041), 5 (1433589132601327616), 6 (1433589132602376192), 7 (1433589132602376193), 8 (1433589132603424768), 9 (1433589132603424769), 10 (1433589132604473344), ... (12 adds)]} 0 7
[junit4:junit4]   1> 208132 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208139 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_12,generation=38}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_13,generation=39}
[junit4:junit4]   1> 208140 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 39
[junit4:junit4]   1> 208142 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@11d4a33 main
[junit4:junit4]   1> 208143 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@11d4a33 main{StandardDirectoryReader(segments_13:112:nrt _s(4.4):C12)}
[junit4:junit4]   1> 208144 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208144 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208149 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@11d4a33 main{StandardDirectoryReader(segments_13:112:nrt _s(4.4):C12)}
[junit4:junit4]   1> 208150 [qtp9348246-17] 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 18
[junit4:junit4]   1> 208159 [qtp9348246-18] 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:junit4]   1> 208164 [qtp9348246-18] 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:junit4]   1> 208168 [qtp9348246-18] 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=13 
[junit4:junit4]   1> 208179 [qtp9348246-14] 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=2 
[junit4:junit4]   1> 208181 [TEST-SolrExampleXMLTest.testPivotFacetsMissing-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testPivotFacetsMissing
[junit4:junit4]   1> 208191 [TEST-SolrExampleXMLTest.testSetNullUpdateOrder-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testSetNullUpdateOrder
[junit4:junit4]   1> 208195 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdateOrder (1433589132678922240)]} 0 1
[junit4:junit4]   1> 208201 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208204 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_13,generation=39}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_14,generation=40}
[junit4:junit4]   1> 208205 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 40
[junit4:junit4]   1> 208207 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@504ce1 main
[junit4:junit4]   1> 208208 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@504ce1 main{StandardDirectoryReader(segments_14:114:nrt _s(4.4):C12 _t(4.4):C1)}
[junit4:junit4]   1> 208208 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208208 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208211 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@504ce1 main{StandardDirectoryReader(segments_14:114:nrt _s(4.4):C12 _t(4.4):C1)}
[junit4:junit4]   1> 208212 [qtp9348246-17] 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:junit4]   1> 208234 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdateOrder (1433589132719816704)]} 0 3
[junit4:junit4]   1> 208239 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208242 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_14,generation=40}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_15,generation=41}
[junit4:junit4]   1> 208242 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 41
[junit4:junit4]   1> 208243 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1d7c142 main
[junit4:junit4]   1> 208244 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1d7c142 main{StandardDirectoryReader(segments_15:118:nrt _s(4.4):C12 _u(4.4):C1)}
[junit4:junit4]   1> 208245 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208245 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208248 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1d7c142 main{StandardDirectoryReader(segments_15:118:nrt _s(4.4):C12 _u(4.4):C1)}
[junit4:junit4]   1> 208249 [qtp9348246-14] 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 10
[junit4:junit4]   1> 208255 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdateOrder&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   1> 208257 [TEST-SolrExampleXMLTest.testSetNullUpdateOrder-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testSetNullUpdateOrder
[junit4:junit4]   1> 208272 [TEST-SolrExampleXMLTest.testLukeHandler-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testLukeHandler
[junit4:junit4]   1> 208279 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@6c5317 realtime
[junit4:junit4]   1> 208280 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589132764905472)} 0 5
[junit4:junit4]   1> 208286 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID0 (1433589132774342656)]} 0 1
[junit4:junit4]   1> 208290 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID1 (1433589132778536960)]} 0 1
[junit4:junit4]   1> 208294 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID2 (1433589132782731264)]} 0 1
[junit4:junit4]   1> 208297 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID3 (1433589132786925568)]} 0 0
[junit4:junit4]   1> 208301 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID4 (1433589132791119872)]} 0 0
[junit4:junit4]   1> 208306 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208308 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_15,generation=41}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_16,generation=42}
[junit4:junit4]   1> 208309 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 42
[junit4:junit4]   1> 208310 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@b7fd52 main
[junit4:junit4]   1> 208311 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@b7fd52 main{StandardDirectoryReader(segments_16:122:nrt _v(4.4):C5)}
[junit4:junit4]   1> 208311 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208311 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208314 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@b7fd52 main{StandardDirectoryReader(segments_16:122:nrt _v(4.4):C5)}
[junit4:junit4]   1> 208315 [qtp9348246-14] 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 9
[junit4:junit4]   1> 208318 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=5 status=0 QTime=0 
[junit4:junit4]   1> 208328 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/admin/luke params={wt=xml&version=2.2} status=0 QTime=4 
[junit4:junit4]   1> 208337 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=xml&version=2.2} status=0 QTime=2 
[junit4:junit4]   1> 208416 [TEST-SolrExampleXMLTest.testLukeHandler-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testLukeHandler
[junit4:junit4]   1> 208425 [TEST-SolrExampleXMLTest.testAddRetrieve-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testAddRetrieve
[junit4:junit4]   1> 208428 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@14eb152 realtime
[junit4:junit4]   1> 208429 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589132924289024)} 0 2
[junit4:junit4]   1> 208437 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1433589132929531904), id2 (1433589132931629056)]} 0 4
[junit4:junit4]   1> 208442 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208445 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_16,generation=42}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_17,generation=43}
[junit4:junit4]   1> 208445 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 43
[junit4:junit4]   1> 208497 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1878d83 main
[junit4:junit4]   1> 208499 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1878d83 main{StandardDirectoryReader(segments_17:126:nrt _w(4.4):C2)}
[junit4:junit4]   1> 208500 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208500 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208505 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1878d83 main{StandardDirectoryReader(segments_17:126:nrt _w(4.4):C2)}
[junit4:junit4]   1> 208506 [qtp9348246-18] 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 64
[junit4:junit4]   1> 208547 [qtp9348246-19] 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=34 
[junit4:junit4]   1> 208553 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1433589133053263872), id2 (1433589133055361024)]} 0 2
[junit4:junit4]   1> 208559 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208564 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_17,generation=43}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_18,generation=44}
[junit4:junit4]   1> 208564 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 44
[junit4:junit4]   1> 208566 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@ed0665 main
[junit4:junit4]   1> 208567 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@ed0665 main{StandardDirectoryReader(segments_18:130:nrt _x(4.4):C2)}
[junit4:junit4]   1> 208567 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208567 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208570 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@ed0665 main{StandardDirectoryReader(segments_18:130:nrt _x(4.4):C2)}
[junit4:junit4]   1> 208571 [qtp9348246-17] 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 12
[junit4:junit4]   1> 208576 [qtp9348246-18] 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=1 
[junit4:junit4]   1> 208585 [qtp9348246-19] 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=2 
[junit4:junit4]   1> 208587 [TEST-SolrExampleXMLTest.testAddRetrieve-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testAddRetrieve
[junit4:junit4]   1> 208615 [TEST-SolrExampleXMLTest.testGetEmptyResults-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testGetEmptyResults
[junit4:junit4]   1> 208621 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1751b15 realtime
[junit4:junit4]   1> 208622 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589133124567040)} 0 3
[junit4:junit4]   1> 208627 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208628 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_18,generation=44}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_19,generation=45}
[junit4:junit4]   1> 208628 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 45
[junit4:junit4]   1> 208629 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@16b5ac7 main
[junit4:junit4]   1> 208631 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@16b5ac7 main{StandardDirectoryReader(segments_18:132:nrt)}
[junit4:junit4]   1> 208631 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208632 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208637 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@16b5ac7 main{StandardDirectoryReader(segments_18:132:nrt)}
[junit4:junit4]   1> 208638 [qtp9348246-17] 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:junit4]   1> 208647 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1433589133150781440)]} 0 3
[junit4:junit4]   1> 208653 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id2 (1433589133159170048)]} 0 2
[junit4:junit4]   1> 208661 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208667 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_19,generation=45}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1a,generation=46}
[junit4:junit4]   1> 208668 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 46
[junit4:junit4]   1> 208671 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@19b4c30 main
[junit4:junit4]   1> 208672 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@19b4c30 main{StandardDirectoryReader(segments_1a:134:nrt _y(4.4):C2)}
[junit4:junit4]   1> 208673 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208672 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208676 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@19b4c30 main{StandardDirectoryReader(segments_1a:134:nrt _y(4.4):C2)}
[junit4:junit4]   1> 208677 [qtp9348246-14] 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:junit4]   1> 208686 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={fl=foofoofoo&q=*:*&wt=xml&version=2.2} hits=2 status=0 QTime=3 
[junit4:junit4]   1> 208687 [TEST-SolrExampleXMLTest.testGetEmptyResults-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testGetEmptyResults
[junit4:junit4]   1> 208700 [TEST-SolrExampleXMLTest.testQueryWithParams-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testQueryWithParams
[junit4:junit4]   1> 208708 [qtp9348246-19] 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=5 
[junit4:junit4]   1> 208711 [TEST-SolrExampleXMLTest.testQueryWithParams-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testQueryWithParams
[junit4:junit4]   1> 208721 [TEST-SolrExampleXMLTest.testPingHandler-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testPingHandler
[junit4:junit4]   1> 208724 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1ce6ea1 realtime
[junit4:junit4]   1> 208725 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589133233618944)} 0 2
[junit4:junit4]   1> 208734 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208735 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1a,generation=46}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1b,generation=47}
[junit4:junit4]   1> 208736 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 47
[junit4:junit4]   1> 208737 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1d5b1d6 main
[junit4:junit4]   1> 208739 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1d5b1d6 main{StandardDirectoryReader(segments_1a:136:nrt)}
[junit4:junit4]   1> 208739 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208740 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208744 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1d5b1d6 main{StandardDirectoryReader(segments_1a:136:nrt)}
[junit4:junit4]   1> 208746 [qtp9348246-17] 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 12
[junit4:junit4]   1> 208752 [qtp9348246-18] 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:junit4]   1> 208766 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/admin/ping params={wt=xml&version=2.2} hits=0 status=0 QTime=6 
[junit4:junit4]   1> 208767 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/admin/ping params={wt=xml&version=2.2} status=0 QTime=7 
[junit4:junit4]   1> 208769 [TEST-SolrExampleXMLTest.testPingHandler-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testPingHandler
[junit4:junit4]   1> 208787 [TEST-SolrExampleXMLTest.testPivotFacets-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testPivotFacets
[junit4:junit4]   1> 208790 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589133302824960)} 0 1
[junit4:junit4]   1> 208795 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208795 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1b,generation=47}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1c,generation=48}
[junit4:junit4]   1> 208796 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 48
[junit4:junit4]   1> 208796 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1eedb3f main
[junit4:junit4]   1> 208797 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1eedb3f main{StandardDirectoryReader(segments_1a:136:nrt)}
[junit4:junit4]   1> 208797 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208797 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208799 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1eedb3f main{StandardDirectoryReader(segments_1a:136:nrt)}
[junit4:junit4]   1> 208800 [qtp9348246-17] 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 5
[junit4:junit4]   1> 208803 [qtp9348246-18] 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:junit4]   1> 208815 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1433589133322747904), 2 (1433589133323796480), 3 (1433589133324845056), 4 (1433589133324845057), 5 (1433589133325893632), 6 (1433589133326942208), 7 (1433589133326942209), 8 (1433589133327990784), 9 (1433589133327990785), 10 (1433589133329039360), ... (12 adds)]} 0 8
[junit4:junit4]   1> 208820 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208826 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1c,generation=48}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1d,generation=49}
[junit4:junit4]   1> 208827 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 49
[junit4:junit4]   1> 208830 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1edbe5d main
[junit4:junit4]   1> 208831 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1edbe5d main{StandardDirectoryReader(segments_1d:138:nrt _z(4.4):C12)}
[junit4:junit4]   1> 208832 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208832 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208834 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1edbe5d main{StandardDirectoryReader(segments_1d:138:nrt _z(4.4):C12)}
[junit4:junit4]   1> 208835 [qtp9348246-14] 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 15
[junit4:junit4]   1> 208839 [qtp9348246-18] 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:junit4]   1> 208840 [qtp9348246-18] 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:junit4]   1> 208841 [qtp9348246-18] 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=2 
[junit4:junit4]   1> 208850 [qtp9348246-19] 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=3 
[junit4:junit4]   1> 208853 [TEST-SolrExampleXMLTest.testPivotFacets-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testPivotFacets
[junit4:junit4]   1> 208870 [TEST-SolrExampleXMLTest.testErrorHandling-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testErrorHandling
[junit4:junit4]   1> 208902 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – Ignoring exception matching ignore_exception
[junit4:junit4]   1> 208902 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/analysis/field params={analysis.fieldtype=int&wt=xml&qt=/analysis/field&analysis.fieldvalue=ignore_exception&version=2.2} status=400 QTime=29 
[junit4:junit4]   1> 208933 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 27
[junit4:junit4]   1> 208934 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – Ignoring exception matching ignore_exception
[junit4:junit4]   1> 208938 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 1
[junit4:junit4]   1> 208938 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – Ignoring exception matching uniqueKey
[junit4:junit4]   1> 208940 [TEST-SolrExampleXMLTest.testErrorHandling-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testErrorHandling
[junit4:junit4]   1> 208954 [TEST-SolrExampleXMLTest.testSetNullUpdates-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testSetNullUpdates
[junit4:junit4]   1> 208961 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdates (1433589133480034304)]} 0 3
[junit4:junit4]   1> 208969 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 208974 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1d,generation=49}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1e,generation=50}
[junit4:junit4]   1> 208975 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 50
[junit4:junit4]   1> 208977 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@592f67 main
[junit4:junit4]   1> 208979 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@592f67 main{StandardDirectoryReader(segments_1e:140:nrt _z(4.4):C12 _10(4.4):C1)}
[junit4:junit4]   1> 208979 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 208980 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 208984 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@592f67 main{StandardDirectoryReader(segments_1e:140:nrt _z(4.4):C12 _10(4.4):C1)}
[junit4:junit4]   1> 208986 [qtp9348246-14] 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:junit4]   1> 208994 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdates (1433589133515685888)]} 0 3
[junit4:junit4]   1> 209003 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 209008 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1e,generation=50}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1f,generation=51}
[junit4:junit4]   1> 209009 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 51
[junit4:junit4]   1> 209012 [qtp9348246-18] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1992f2e main
[junit4:junit4]   1> 209013 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1992f2e main{StandardDirectoryReader(segments_1f:144:nrt _z(4.4):C12 _11(4.4):C1)}
[junit4:junit4]   1> 209014 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 209014 [qtp9348246-18] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 209018 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1992f2e main{StandardDirectoryReader(segments_1f:144:nrt _z(4.4):C12 _11(4.4):C1)}
[junit4:junit4]   1> 209019 [qtp9348246-18] 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:junit4]   1> 209025 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   1> 209028 [TEST-SolrExampleXMLTest.testSetNullUpdates-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testSetNullUpdates
[junit4:junit4]   1> 209046 [TEST-SolrExampleXMLTest.testChineseDefaults-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testChineseDefaults
[junit4:junit4]   1> 209052 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1b5438f realtime
[junit4:junit4]   1> 209053 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589133576503296)} 0 3
[junit4:junit4]   1> 209062 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 209063 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1f,generation=51}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1g,generation=52}
[junit4:junit4]   1> 209064 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 52
[junit4:junit4]   1> 209066 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@f7a074 main
[junit4:junit4]   1> 209068 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@f7a074 main{StandardDirectoryReader(segments_1f:146:nrt)}
[junit4:junit4]   1> 209068 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 209068 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 209073 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@f7a074 main{StandardDirectoryReader(segments_1f:146:nrt)}
[junit4:junit4]   1> 209075 [qtp9348246-17] 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:junit4]   1> 209081 [qtp9348246-18] 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:junit4]   1> 209088 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 209093 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1g,generation=52}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1h,generation=53}
[junit4:junit4]   1> 209094 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 53
[junit4:junit4]   1> 209096 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@1e1e8de main
[junit4:junit4]   1> 209098 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@1e1e8de main{StandardDirectoryReader(segments_1h:148:nrt _12(4.4):C1)}
[junit4:junit4]   1> 209099 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 209098 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 209102 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1e1e8de main{StandardDirectoryReader(segments_1h:148:nrt _12(4.4):C1)}
[junit4:junit4]   1> 209103 [qtp9348246-19] 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=[42 (1433589133614252032)],commit=} 0 17
[junit4:junit4]   1> 209108 [qtp9348246-14] 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:junit4]   1> 209109 [TEST-SolrExampleXMLTest.testChineseDefaults-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testChineseDefaults
[junit4:junit4]   1> 209117 [TEST-SolrExampleXMLTest.testExampleConfig-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testExampleConfig
[junit4:junit4]   1> 209121 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@16139ed realtime
[junit4:junit4]   1> 209121 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589133649903616)} 0 1
[junit4:junit4]   1> 209125 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1112211111 (1433589133654097920)]} 0 1
[junit4:junit4]   1> 209131 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 209136 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1h,generation=53}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1i,generation=54}
[junit4:junit4]   1> 209137 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 54
[junit4:junit4]   1> 209140 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@e8c823 main
[junit4:junit4]   1> 209142 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@e8c823 main{StandardDirectoryReader(segments_1i:152:nrt _13(4.4):C1)}
[junit4:junit4]   1> 209143 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 209142 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 209147 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@e8c823 main{StandardDirectoryReader(segments_1i:152:nrt _13(4.4):C1)}
[junit4:junit4]   1> 209149 [qtp9348246-19] 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 18
[junit4:junit4]   1> 209158 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 209159 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1i,generation=54}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1j,generation=55}
[junit4:junit4]   1> 209160 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 55
[junit4:junit4]   1> 209162 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@b8c6fb main
[junit4:junit4]   1> 209163 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 209163 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@b8c6fb main{StandardDirectoryReader(segments_1i:152:nrt _13(4.4):C1)}
[junit4:junit4]   1> 209164 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 209169 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@b8c6fb main{StandardDirectoryReader(segments_1i:152:nrt _13(4.4):C1)}
[junit4:junit4]   1> 209170 [qtp9348246-14] 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 13
[junit4:junit4]   1> 209175 [qtp9348246-17] 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:junit4]   1> 209190 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 (1433589133714915328), 3 (1433589133718061056), 4 (1433589133720158208), 5 (1433589133722255360)]} 0 9
[junit4:junit4]   1> 209199 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 209204 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1j,generation=55}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1k,generation=56}
[junit4:junit4]   1> 209205 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 56
[junit4:junit4]   1> 209206 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@103c74d main
[junit4:junit4]   1> 209207 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@103c74d main{StandardDirectoryReader(segments_1k:154:nrt _13(4.4):C1 _14(4.4):C4)}
[junit4:junit4]   1> 209208 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 209208 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 209210 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@103c74d main{StandardDirectoryReader(segments_1k:154:nrt _13(4.4):C1 _14(4.4):C4)}
[junit4:junit4]   1> 209211 [qtp9348246-19] 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 12
[junit4:junit4]   1> 209216 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 209237 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1k,generation=56}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1l,generation=57}
[junit4:junit4]   1> 209238 [qtp9348246-14] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 57
[junit4:junit4]   1> 209240 [qtp9348246-14] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@caedc8 main
[junit4:junit4]   1> 209240 [qtp9348246-14] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 209240 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@caedc8 main{StandardDirectoryReader(segments_1l:156:nrt _15(4.4):C5)}
[junit4:junit4]   1> 209241 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 209246 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@caedc8 main{StandardDirectoryReader(segments_1l:156:nrt _15(4.4):C5)}
[junit4:junit4]   1> 209247 [qtp9348246-14] 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 31
[junit4:junit4]   1> 209281 [qtp9348246-18] 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=28 
[junit4:junit4]   1> 209291 [qtp9348246-19] 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=3 
[junit4:junit4]   1> 209304 [qtp9348246-14] 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=6 
[junit4:junit4]   1> 209306 [TEST-SolrExampleXMLTest.testExampleConfig-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testExampleConfig
[junit4:junit4]   1> 209323 [TEST-SolrExampleXMLTest.testAugmentFields-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Starting testAugmentFields
[junit4:junit4]   1> 209328 [qtp9348246-17] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@15490cd realtime
[junit4:junit4]   1> 209329 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1433589133865910272)} 0 3
[junit4:junit4]   1> 209337 [qtp9348246-18] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[111 (1433589133874298880)]} 0 3
[junit4:junit4]   1> 209346 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 209352 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1l,generation=57}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1m,generation=58}
[junit4:junit4]   1> 209353 [qtp9348246-19] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 58
[junit4:junit4]   1> 209357 [qtp9348246-19] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@9714ab main
[junit4:junit4]   1> 209358 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@9714ab main{StandardDirectoryReader(segments_1m:160:nrt _16(4.4):C1)}
[junit4:junit4]   1> 209359 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 209359 [qtp9348246-19] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 209364 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@9714ab main{StandardDirectoryReader(segments_1m:160:nrt _16(4.4):C1)}
[junit4:junit4]   1> 209365 [qtp9348246-19] 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 19
[junit4:junit4]   1> 209374 [qtp9348246-14] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[222 (1433589133913096192)]} 0 3
[junit4:junit4]   1> 209383 [qtp9348246-17] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 209390 [qtp9348246-17] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apa

[...truncated too long message...]

che.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 209968 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@1b9981a main{StandardDirectoryReader(segments_1v:180:nrt)}
[junit4:junit4]   1> 209969 [qtp9348246-17] 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 8
[junit4:junit4]   1> 209973 [qtp9348246-18] 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:junit4]   1> 209980 [qtp9348246-19] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id3 (1433589134549581824)]} 0 3
[junit4:junit4]   1> 209985 [qtp9348246-14] 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:junit4]   1> 210480 [commitScheduler-7-thread-1] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4]   1> 210486 [commitScheduler-7-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@333509 main
[junit4:junit4]   1> 210487 [commitScheduler-7-thread-1] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 210487 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@333509 main{StandardDirectoryReader(segments_1w:182:nrt _1c(4.4):C1)}
[junit4:junit4]   1> 210488 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 210491 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@333509 main{StandardDirectoryReader(segments_1w:182:nrt _1c(4.4):C1)}
[junit4:junit4]   1> 210990 [qtp9348246-14] 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:junit4]   1> 210999 [qtp9348246-17] INFO  org.apache.solr.update.processor.LogUpdateProcessor  – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id4 (1433589135617032192)]} 0 3
[junit4:junit4]   1> 211498 [commitScheduler-7-thread-1] INFO  org.apache.solr.update.UpdateHandler  – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4]   1> 211505 [commitScheduler-7-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  – Opening Searcher@187c33b main
[junit4:junit4]   1> 211506 [commitScheduler-7-thread-1] INFO  org.apache.solr.update.UpdateHandler  – end_commit_flush
[junit4:junit4]   1> 211507 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener sending requests to Searcher@187c33b main{StandardDirectoryReader(segments_1w:184:nrt _1c(4.4):C1 _1d(4.4):C1)}
[junit4:junit4]   1> 211508 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – QuerySenderListener done.
[junit4:junit4]   1> 211511 [searcherExecutor-4-thread-1] INFO  org.apache.solr.core.SolrCore  – [collection1] Registered new searcher Searcher@187c33b main{StandardDirectoryReader(segments_1w:184:nrt _1c(4.4):C1 _1d(4.4):C1)}
[junit4:junit4]   1> 212005 [qtp9348246-18] INFO  org.apache.solr.core.SolrCore  – [collection1] webapp=/solr path=/select params={q=id:id4&wt=xml&version=2.2} hits=1 status=0 QTime=1 
[junit4:junit4]   1> 212008 [TEST-SolrExampleXMLTest.testCommitWithinOnAdd-seed#[5E6DB6DEB79D5254]] INFO  org.apache.solr.SolrTestCaseJ4  – ###Ending testCommitWithinOnAdd
[junit4:junit4]   1> 212070 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.CoreContainer  – Shutting down CoreContainer instance=5633964
[junit4:junit4]   1> 212071 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCore  – [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@13296f2
[junit4:junit4]   1> 212093 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.update.UpdateHandler  – closing DirectUpdateHandler2{commits=68,autocommit maxTime=15000ms,autocommits=1,soft autocommits=3,optimizes=2,rollbacks=0,expungeDeletes=0,docsPending=2,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=357,cumulative_deletesById=2,cumulative_deletesByQuery=37,cumulative_errors=1}
[junit4:junit4]   1> 212094 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.update.SolrCoreState  – Closing SolrCoreState
[junit4:junit4]   1> 212094 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.update.DefaultSolrCoreState  – SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> 212094 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.update.DefaultSolrCoreState  – closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> 212095 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCore  – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1w,generation=68}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@131370f lockFactory=org.apache.lucene.store.NativeFSLockFactory@10be64b),segFN=segments_1x,generation=69}
[junit4:junit4]   1> 212096 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCore  – newest commit generation = 69
[junit4:junit4]   1> 212098 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.SolrCore  – [collection1] Closing main searcher on request.
[junit4:junit4]   1> 212098 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  – Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> 212099 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  – looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930;done=false>>]
[junit4:junit4]   1> 212099 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  – Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930
[junit4:junit4]   1> 212100 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  – looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930/index;done=false>>]
[junit4:junit4]   1> 212100 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  – Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1367176927930/index
[junit4:junit4]   1> 212101 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.eclipse.jetty.server.handler.ContextHandler  – stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> 212155 [SUITE-SolrExampleXMLTest-seed#[5E6DB6DEB79D5254]-worker] INFO  org.apache.solr.SolrTestCaseJ4  – ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=478), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=478)), sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=ko_KR, timezone=America/St_Vincent
[junit4:junit4]   2> NOTE: Linux 3.2.0-40-generic i386/Oracle Corporation 1.7.0_21 (32-bit)/cpus=8,threads=1,free=150736416,total=206503936
[junit4:junit4]   2> NOTE: All tests run in this JVM: [SolrExampleXMLTest]
[junit4:junit4] Completed on J0 in 212.94s, 26 tests, 1 error <<< FAILURES!

[...truncated 43 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:384: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:364: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:194: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:437: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1243: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:887: There were test failures: 45 suites, 268 tests, 1 error

Total time: 39 minutes 40 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.7.0_21 -server -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure