lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-MacOSX (64bit/jdk1.7.0) - Build # 452 - Still Failing!
Date Tue, 14 May 2013 20:53:57 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/452/
Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  org.apache.solr.client.solrj.SolrExampleXMLTest.testAddRetrieve

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

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:54187/solr/collection1
	at __randomizedtesting.SeedInfo.seed([AF041A5DE31BB081:4CD78BFAD8A49A38]: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.commit(SolrServer.java:168)
	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
	at org.apache.solr.client.solrj.SolrExampleTests.testAddRetrieve(SolrExampleTests.java:243)
	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 10580 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.SolrExampleXMLTest
[junit4:junit4]   1> 160839 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.SolrJettyTestBase  ? Randomized ssl (true) and clientAuth (false)
[junit4:junit4]   1> 160840 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.SolrTestCaseJ4  ? ####initCore
[junit4:junit4]   1> 160841 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.SolrTestCaseJ4  ? ####initCore end
[junit4:junit4]   1> 160842 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.eclipse.jetty.server.Server  ? jetty-8.1.10.v20130312
[junit4:junit4]   1> 160875 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-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> 160884 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.eclipse.jetty.server.AbstractConnector  ? Started SslSelectChannelConnector@127.0.0.1:54187
[junit4:junit4]   1> 160886 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  ? SolrDispatchFilter.init()
[junit4:junit4]   1> 160887 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrResourceLoader  ? JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> 160888 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrResourceLoader  ? using system property solr.solr.home: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr
[junit4:junit4]   1> 160890 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.CoreContainer  ? looking for solr config file: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/solr.xml
[junit4:junit4]   1> 160891 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.CoreContainer  ? New CoreContainer 1985493745
[junit4:junit4]   1> 160893 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.CoreContainer  ? Loading CoreContainer using Solr Home: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/'
[junit4:junit4]   1> 160902 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrResourceLoader  ? new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/'
[junit4:junit4]   1> 161227 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr
[junit4:junit4]   1> 161229 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/.svn
[junit4:junit4]   1> 161233 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/.svn/prop-base
[junit4:junit4]   1> 161237 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/.svn/props
[junit4:junit4]   1> 161238 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/.svn/text-base
[junit4:junit4]   1> 161242 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/.svn/tmp
[junit4:junit4]   1> 161244 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/.svn/tmp/prop-base
[junit4:junit4]   1> 161246 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/.svn/tmp/props
[junit4:junit4]   1> 161247 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/.svn/tmp/text-base
[junit4:junit4]   1> 161249 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/bin
[junit4:junit4]   1> 161250 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/bin/.svn
[junit4:junit4]   1> 161255 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/bin/.svn/prop-base
[junit4:junit4]   1> 161257 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/bin/.svn/props
[junit4:junit4]   1> 161258 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/bin/.svn/text-base
[junit4:junit4]   1> 161260 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/bin/.svn/tmp
[junit4:junit4]   1> 161262 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/bin/.svn/tmp/prop-base
[junit4:junit4]   1> 161264 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/bin/.svn/tmp/props
[junit4:junit4]   1> 161272 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Looking for cores in /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/bin/.svn/tmp/text-base
[junit4:junit4]   1> 161273 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCoreDiscoverer  ? Discovered properties file /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/core.properties, adding to cores
[junit4:junit4]   1> 161287 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting socketTimeout to: 0
[junit4:junit4]   1> 161289 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting urlScheme to: http://
[junit4:junit4]   1> 161290 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting connTimeout to: 0
[junit4:junit4]   1> 161291 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> 161292 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting corePoolSize to: 0
[junit4:junit4]   1> 161293 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> 161294 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> 161295 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting sizeOfQueue to: -1
[junit4:junit4]   1> 161296 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.handler.component.HttpShardHandlerFactory  ? Setting fairnessPolicy to: false
[junit4:junit4]   1> 161298 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-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> 161312 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.logging.LogWatcher  ? Registering Log Listener
[junit4:junit4]   1> 161331 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.CoreContainer  ? Creating SolrCore 'collection1' using instanceDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1
[junit4:junit4]   1> 161333 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrResourceLoader  ? new SolrResourceLoader for directory: '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/'
[junit4:junit4]   1> 161386 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrConfig  ? Adding specified lib dirs to ClassLoader
[junit4:junit4]   1> 161388 [coreLoadExecutor-150-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4:junit4]   1> 161389 [coreLoadExecutor-150-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 161390 [coreLoadExecutor-150-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4:junit4]   1> 161391 [coreLoadExecutor-150-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 161392 [coreLoadExecutor-150-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4:junit4]   1> 161393 [coreLoadExecutor-150-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 161394 [coreLoadExecutor-150-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4:junit4]   1> 161395 [coreLoadExecutor-150-thread-1] WARN  org.apache.solr.core.SolrResourceLoader  ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/../../../dist).
[junit4:junit4]   1> 161396 [coreLoadExecutor-150-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> 161605 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrConfig  ? Using Lucene MatchVersion: LUCENE_43
[junit4:junit4]   1> 161890 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.Config  ? Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> 161892 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.schema.IndexSchema  ? Reading Solr Schema from schema.xml
[junit4:junit4]   1> 161933 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.schema.IndexSchema  ? [collection1] Schema name=example
[junit4:junit4]   1> 164072 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.schema.IndexSchema  ? unique key field: id
[junit4:junit4]   1> 164112 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.schema.FileExchangeRateProvider  ? Reloading exchange rates from file currency.xml
[junit4:junit4]   1> 164148 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.schema.FileExchangeRateProvider  ? Reloading exchange rates from file currency.xml
[junit4:junit4]   1> 164271 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> 164274 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Opening new SolrCore at /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/, dataDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956/
[junit4:junit4]   1> 164275 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.JmxMonitoredMap  ? JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5bb6e314
[junit4:junit4]   1> 164276 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4]   1> 164306 [coreLoadExecutor-150-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> 164307 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> 164309 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.CachingDirectoryFactory  ? return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956
[junit4:junit4]   1> 164310 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? New index directory detected: old=null new=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956/index/
[junit4:junit4]   1> 164310 [coreLoadExecutor-150-thread-1] WARN  org.apache.solr.core.SolrCore  ? [collection1] Solr index directory '/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956/index' doesn't exist. Creating new index...
[junit4:junit4]   1> 164311 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.CachingDirectoryFactory  ? return new directory for /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956/index
[junit4:junit4]   1> 164317 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1,generation=1}
[junit4:junit4]   1> 164318 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 1
[junit4:junit4]   1> 164320 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? created json: solr.JSONResponseWriter
[junit4:junit4]   1> 164321 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4]   1> 164329 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? created velocity: solr.VelocityResponseWriter
[junit4:junit4]   1> 164329 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? created xslt: solr.XSLTResponseWriter
[junit4:junit4]   1> 164330 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.response.XSLTResponseWriter  ? xsltCacheLifetimeSeconds=5
[junit4:junit4]   1> 164333 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /select: solr.SearchHandler
[junit4:junit4]   1> 164334 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /query: solr.SearchHandler
[junit4:junit4]   1> 164335 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> 164337 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /browse: solr.SearchHandler
[junit4:junit4]   1> 164341 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> 164342 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4]   1> 164343 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update/csv: solr.CSVRequestHandler
[junit4:junit4]   1> 164344 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   1> 164346 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4]   1> 164346 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4]   1> 164347 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4]   1> 164348 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   1> 164349 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4]   1> 164351 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /admin/: solr.admin.AdminHandlers
[junit4:junit4]   1> 164352 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /admin/ping: solr.PingRequestHandler
[junit4:junit4]   1> 164355 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   1> 164356 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /replication: solr.ReplicationHandler
[junit4:junit4]   1> 164357 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 164359 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /spell: solr.SearchHandler
[junit4:junit4]   1> 164359 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 164361 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /tvrh: solr.SearchHandler
[junit4:junit4]   1> 164362 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 164362 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /terms: solr.SearchHandler
[junit4:junit4]   1> 164363 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4]   1> 164365 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.RequestHandlers  ? created /elevate: solr.SearchHandler
[junit4:junit4]   1> 164396 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  ? xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 164401 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  ? xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 164405 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.handler.loader.XMLLoader  ? xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> 164430 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@790235e3 main
[junit4:junit4]   1> 164432 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? Clearing tlog files, tlogDir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956/tlog
[junit4:junit4]   1> 164435 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? Hard AutoCommit: if uncommited for 15000ms; 
[junit4:junit4]   1> 164436 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.SolrCore  ? Soft AutoCommit: disabled
[junit4:junit4]   1> 164437 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? Initializing spell checkers
[junit4:junit4]   1> 164441 [coreLoadExecutor-150-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> 164442 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? No queryConverter defined, using default converter
[junit4:junit4]   1> 164445 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.handler.component.QueryElevationComponent  ? Loading QueryElevation from: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/example/solr/collection1/conf/elevate.xml
[junit4:junit4]   1> 164517 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.handler.ReplicationHandler  ? Commits will be reserved for  10000
[junit4:junit4]   1> 164531 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@790235e3 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> 164536 [searcherExecutor-151-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=2 
[junit4:junit4]   1> 164537 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 164537 [searcherExecutor-151-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? Loading spell index for spellchecker: default
[junit4:junit4]   1> 164538 [searcherExecutor-151-thread-1] INFO  org.apache.solr.handler.component.SpellCheckComponent  ? Loading spell index for spellchecker: wordbreak
[junit4:junit4]   1> 164540 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@790235e3 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> 164544 [coreLoadExecutor-150-thread-1] INFO  org.apache.solr.core.CoreContainer  ? registering core: collection1
[junit4:junit4]   1> 164549 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  ? user.dir=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0
[junit4:junit4]   1> 164550 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.servlet.SolrDispatchFilter  ? SolrDispatchFilter.init() done
[junit4:junit4]   1> 164551 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.SolrJettyTestBase  ? Jetty Assigned Port#54187
[junit4:junit4]   2> Creating dataDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-SolrExampleXMLTest-1368564418956
[junit4:junit4]   1> 164578 [TEST-SolrExampleXMLTest.testContentStreamRequest-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testContentStreamRequest
[junit4:junit4]   1> 164579 [TEST-SolrExampleXMLTest.testContentStreamRequest-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.client.solrj.impl.HttpClientUtil  ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> 164680 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1,generation=1}
[junit4:junit4]   1> 164699 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 1
[junit4:junit4]   1> 164704 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@52778b1e realtime
[junit4:junit4]   1> 164712 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435043808191447040)} 0 38
[junit4:junit4]   1> 164722 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 164726 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1,generation=1}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_2,generation=2}
[junit4:junit4]   1> 164729 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 2
[junit4:junit4]   1> 164731 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3b95266b main
[junit4:junit4]   1> 164732 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 164733 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3b95266b main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4]   1> 164734 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 164737 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3b95266b main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4]   1> 164738 [qtp1554147247-243] 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> 164772 [qtp1554147247-244] 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> 164816 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 164834 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_2,generation=2}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_3,generation=3}
[junit4:junit4]   1> 164835 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 3
[junit4:junit4]   1> 164849 [qtp1554147247-245] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4076adf8 main
[junit4:junit4]   1> 164850 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 164851 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@4076adf8 main{StandardDirectoryReader(segments_3:3:nrt _0(4.4):C10)}
[junit4:junit4]   1> 164851 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 164857 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@4076adf8 main{StandardDirectoryReader(segments_3:3:nrt _0(4.4):C10)}
[junit4:junit4]   1> 164864 [qtp1554147247-245] 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 (1435043808306790400), 0553579908 (1435043808313081856), 055357342X (1435043808315179008), 0553293354 (1435043808317276160), 0812521390 (1435043808326713344), 0812550706 (1435043808328810496), 0441385532 (1435043808330907648), 0380014300 (1435043808333004800), 0805080481 (1435043808335101952), 080508049X (1435043808337199104)],commit=} 0 81
[junit4:junit4]   1> 164878 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=10 status=0 QTime=1 
[junit4:junit4]   1> 164888 [TEST-SolrExampleXMLTest.testContentStreamRequest-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testContentStreamRequest
[junit4:junit4]   1> 164926 [TEST-SolrExampleXMLTest.testStreamingRequest-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testStreamingRequest
[junit4:junit4]   1> 164935 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@271a95f8 realtime
[junit4:junit4]   1> 164938 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435043808463028224)} 0 6
[junit4:junit4]   1> 164947 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 164949 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_3,generation=3}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_4,generation=4}
[junit4:junit4]   1> 164950 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 4
[junit4:junit4]   1> 164951 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@73ab95f3 main
[junit4:junit4]   1> 164968 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 164969 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@73ab95f3 main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   1> 164970 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 164973 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@73ab95f3 main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4]   1> 164974 [qtp1554147247-248] 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 27
[junit4:junit4]   1> 165001 [qtp1554147247-244] 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> 165021 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 165027 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_4,generation=4}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_5,generation=5}
[junit4:junit4]   1> 165028 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 5
[junit4:junit4]   1> 165035 [qtp1554147247-245] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@14aa9552 main
[junit4:junit4]   1> 165036 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@14aa9552 main{StandardDirectoryReader(segments_5:7:nrt _1(4.4):C10)}
[junit4:junit4]   1> 165036 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 165040 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@14aa9552 main{StandardDirectoryReader(segments_5:7:nrt _1(4.4):C10)}
[junit4:junit4]   1> 165037 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 165042 [qtp1554147247-245] 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 (1435043808543768576), 1 (1435043808546914304), 2 (1435043808546914305), 3 (1435043808547962880), 4 (1435043808549011456), 5 (1435043808549011457), 6 (1435043808550060032), 7 (1435043808553205760), 8 (1435043808553205761), 9 (1435043808554254336)],commit=} 0 32
[junit4:junit4]   1> 165061 [qtp1554147247-246] 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=8 
[junit4:junit4]   1> 165070 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1 
[junit4:junit4]   1> 165074 [TEST-SolrExampleXMLTest.testStreamingRequest-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testStreamingRequest
[junit4:junit4]   1> 165146 [TEST-SolrExampleXMLTest.testLukeHandler-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testLukeHandler
[junit4:junit4]   1> 165179 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3752fdda realtime
[junit4:junit4]   1> 165182 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435043808717832192)} 0 7
[junit4:junit4]   1> 165194 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID0 (1435043808734609408)]} 0 2
[junit4:junit4]   1> 165213 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID1 (1435043808755580928)]} 0 2
[junit4:junit4]   1> 165223 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID2 (1435043808766066688)]} 0 1
[junit4:junit4]   1> 165231 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID3 (1435043808774455296)]} 0 1
[junit4:junit4]   1> 165239 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID4 (1435043808783892480)]} 0 0
[junit4:junit4]   1> 165247 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 165257 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_5,generation=5}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_6,generation=6}
[junit4:junit4]   1> 165258 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 6
[junit4:junit4]   1> 165261 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3732181b main
[junit4:junit4]   1> 165262 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 165262 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3732181b main{StandardDirectoryReader(segments_6:11:nrt _2(4.4):C5)}
[junit4:junit4]   1> 165264 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 165268 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3732181b main{StandardDirectoryReader(segments_6:11:nrt _2(4.4):C5)}
[junit4:junit4]   1> 165269 [qtp1554147247-248] 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 22
[junit4:junit4]   1> 165276 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=5 status=0 QTime=1 
[junit4:junit4]   1> 165283 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/admin/luke params={wt=xml&version=2.2} status=0 QTime=1 
[junit4:junit4]   1> 165295 [qtp1554147247-246] 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> 165436 [TEST-SolrExampleXMLTest.testLukeHandler-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testLukeHandler
[junit4:junit4]   1> 165488 [TEST-SolrExampleXMLTest.testGetEmptyResults-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testGetEmptyResults
[junit4:junit4]   1> 165504 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@116194d9 realtime
[junit4:junit4]   1> 165505 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435043809054425088)} 0 10
[junit4:junit4]   1> 165515 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 165521 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_6,generation=6}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_7,generation=7}
[junit4:junit4]   1> 165522 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 7
[junit4:junit4]   1> 165523 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5c6264af main
[junit4:junit4]   1> 165524 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 165526 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5c6264af main{StandardDirectoryReader(segments_6:13:nrt)}
[junit4:junit4]   1> 165527 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 165532 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5c6264af main{StandardDirectoryReader(segments_6:13:nrt)}
[junit4:junit4]   1> 165534 [qtp1554147247-243] 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> 165593 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1435043809144602624)]} 0 10
[junit4:junit4]   1> 165611 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id2 (1435043809172914176)]} 0 2
[junit4:junit4]   1> 165626 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 165645 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_7,generation=7}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_8,generation=8}
[junit4:junit4]   1> 165646 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 8
[junit4:junit4]   1> 165648 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@20d4fbcb main
[junit4:junit4]   1> 165650 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 165651 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@20d4fbcb main{StandardDirectoryReader(segments_8:15:nrt _3(4.4):C2)}
[junit4:junit4]   1> 165652 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 165655 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@20d4fbcb main{StandardDirectoryReader(segments_8:15:nrt _3(4.4):C2)}
[junit4:junit4]   1> 165657 [qtp1554147247-247] 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 31
[junit4:junit4]   1> 165673 [qtp1554147247-243] 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=1 
[junit4:junit4]   1> 165680 [TEST-SolrExampleXMLTest.testGetEmptyResults-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testGetEmptyResults
[junit4:junit4]   1> 165716 [TEST-SolrExampleXMLTest.testAugmentFields-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testAugmentFields
[junit4:junit4]   1> 165737 [qtp1554147247-244] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@c33d21f realtime
[junit4:junit4]   1> 165738 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435043809290354688)} 0 16
[junit4:junit4]   1> 165749 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[111 (1435043809316569088)]} 0 2
[junit4:junit4]   1> 165756 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 165762 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_8,generation=8}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_9,generation=9}
[junit4:junit4]   1> 165762 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 9
[junit4:junit4]   1> 165767 [qtp1554147247-245] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@26aff075 main
[junit4:junit4]   1> 165774 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 165775 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@26aff075 main{StandardDirectoryReader(segments_9:19:nrt _4(4.4):C1)}
[junit4:junit4]   1> 165776 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 165779 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@26aff075 main{StandardDirectoryReader(segments_9:19:nrt _4(4.4):C1)}
[junit4:junit4]   1> 165786 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 30
[junit4:junit4]   1> 165800 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[222 (1435043809367949312)]} 0 5
[junit4:junit4]   1> 165810 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 165818 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_9,generation=9}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_a,generation=10}
[junit4:junit4]   1> 165820 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 10
[junit4:junit4]   1> 165824 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@78a7023c main
[junit4:junit4]   1> 165825 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 165827 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@78a7023c main{StandardDirectoryReader(segments_a:21:nrt _4(4.4):C1 _5(4.4):C1)}
[junit4:junit4]   1> 165828 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 165832 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@78a7023c main{StandardDirectoryReader(segments_a:21:nrt _4(4.4):C1 _5(4.4):C1)}
[junit4:junit4]   1> 165833 [qtp1554147247-247] 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> 165853 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={sort=price+asc&fl=id,price,[docid],[explain+style%3Dnl],score,aaa:[value+v%3Daaa],ten:[value+v%3D10+t%3Dint]&q=*:*&wt=xml&version=2.2} hits=2 status=0 QTime=2 
[junit4:junit4]   1> 165865 [TEST-SolrExampleXMLTest.testAugmentFields-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testAugmentFields
[junit4:junit4]   1> 165898 [TEST-SolrExampleXMLTest.testUpdateMultiValuedField-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testUpdateMultiValuedField
[junit4:junit4]   1> 165925 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1435043809494827008)]} 0 9
[junit4:junit4]   1> 165935 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 165940 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_a,generation=10}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_b,generation=11}
[junit4:junit4]   1> 165941 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 11
[junit4:junit4]   1> 165944 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@33d59c6b main
[junit4:junit4]   1> 165946 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@33d59c6b main{StandardDirectoryReader(segments_b:23:nrt _4(4.4):C1 _5(4.4):C1 _6(4.4):C1)}
[junit4:junit4]   1> 165948 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 165946 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 166003 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@33d59c6b main{StandardDirectoryReader(segments_b:23:nrt _4(4.4):C1 _5(4.4):C1 _6(4.4):C1)}
[junit4:junit4]   1> 166005 [qtp1554147247-248] 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 70
[junit4:junit4]   1> 166021 [qtp1554147247-246] 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> 166031 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1435043809612267520)]} 0 3
[junit4:junit4]   1> 166046 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 166054 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_b,generation=11}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_c,generation=12}
[junit4:junit4]   1> 166055 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 12
[junit4:junit4]   1> 166119 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@165caedc main
[junit4:junit4]   1> 166122 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 166123 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@165caedc main{StandardDirectoryReader(segments_c:27:nrt _4(4.4):C1 _5(4.4):C1 _7(4.4):C1)}
[junit4:junit4]   1> 166123 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 166126 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@165caedc main{StandardDirectoryReader(segments_c:27:nrt _4(4.4):C1 _5(4.4):C1 _7(4.4):C1)}
[junit4:junit4]   1> 166127 [qtp1554147247-243] 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 81
[junit4:junit4]   1> 166136 [qtp1554147247-244] 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> 166148 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[123 (1435043809734950912)]} 0 2
[junit4:junit4]   1> 166167 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 166176 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_c,generation=12}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_d,generation=13}
[junit4:junit4]   1> 166178 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 13
[junit4:junit4]   1> 166194 [qtp1554147247-245] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5cff590c main
[junit4:junit4]   1> 166196 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 166197 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5cff590c main{StandardDirectoryReader(segments_d:31:nrt _4(4.4):C1 _5(4.4):C1 _8(4.4):C1)}
[junit4:junit4]   1> 166197 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 166201 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5cff590c main{StandardDirectoryReader(segments_d:31:nrt _4(4.4):C1 _5(4.4):C1 _8(4.4):C1)}
[junit4:junit4]   1> 166203 [qtp1554147247-245] 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 37
[junit4:junit4]   1> 166211 [qtp1554147247-246] 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> 166215 [TEST-SolrExampleXMLTest.testUpdateMultiValuedField-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testUpdateMultiValuedField
[junit4:junit4]   1> 166281 [TEST-SolrExampleXMLTest.testErrorHandling-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testErrorHandling
[junit4:junit4]   1> 166288 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? Ignoring exception matching ignore_exception
[junit4:junit4]   1> 166289 [qtp1554147247-247] 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=2 
[junit4:junit4]   1> 166302 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 1
[junit4:junit4]   1> 166304 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? Ignoring exception matching ignore_exception
[junit4:junit4]   1> 166333 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 2
[junit4:junit4]   1> 166335 [qtp1554147247-248] ERROR org.apache.solr.core.SolrCore  ? org.apache.solr.common.SolrException: Document contains multiple values for uniqueKey field: id=[DOCID, DOCID2]
[junit4:junit4]   1> 	at org.apache.solr.update.AddUpdateCommand.getIndexedId(AddUpdateCommand.java:91)
[junit4:junit4]   1> 	at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:522)
[junit4:junit4]   1> 	at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:401)
[junit4:junit4]   1> 	at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:100)
[junit4:junit4]   1> 	at org.apache.solr.handler.loader.XMLLoader.processUpdate(XMLLoader.java:246)
[junit4:junit4]   1> 	at org.apache.solr.handler.loader.XMLLoader.load(XMLLoader.java:173)
[junit4:junit4]   1> 	at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:92)
[junit4:junit4]   1> 	at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
[junit4:junit4]   1> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4:junit4]   1> 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:1832)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:656)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:359)
[junit4:junit4]   1> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:155)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4]   1> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4:junit4]   1> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4:junit4]   1> 	at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4:junit4]   1> 	at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4]   1> 	at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4]   1> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4]   1> 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   1> 
[junit4:junit4]   1> 166343 [TEST-SolrExampleXMLTest.testErrorHandling-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testErrorHandling
[junit4:junit4]   1> 166427 [TEST-SolrExampleXMLTest.testChineseDefaults-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testChineseDefaults
[junit4:junit4]   1> 166462 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@373a80e7 realtime
[junit4:junit4]   1> 166465 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435043810040086528)} 0 28
[junit4:junit4]   1> 166477 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 166480 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_d,generation=13}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_e,generation=14}
[junit4:junit4]   1> 166481 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 14
[junit4:junit4]   1> 166546 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4cfb2bce main
[junit4:junit4]   1> 166548 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@4cfb2bce main{StandardDirectoryReader(segments_d:33:nrt)}
[junit4:junit4]   1> 166548 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 166554 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@4cfb2bce main{StandardDirectoryReader(segments_d:33:nrt)}
[junit4:junit4]   1> 166557 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 166558 [qtp1554147247-243] 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 81
[junit4:junit4]   1> 166575 [qtp1554147247-248] 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> 166584 [qtp1554147247-246] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 166589 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_e,generation=14}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_f,generation=15}
[junit4:junit4]   1> 166590 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 15
[junit4:junit4]   1> 166593 [qtp1554147247-246] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@34843316 main
[junit4:junit4]   1> 166594 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@34843316 main{StandardDirectoryReader(segments_f:35:nrt _9(4.4):C1)}
[junit4:junit4]   1> 166596 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 166600 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@34843316 main{StandardDirectoryReader(segments_f:35:nrt _9(4.4):C1)}
[junit4:junit4]   1> 166604 [qtp1554147247-246] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 166605 [qtp1554147247-246] 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 (1435043810192130048)],commit=} 0 24
[junit4:junit4]   1> 166621 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=????&wt=xml&version=2.2} hits=1 status=0 QTime=4 
[junit4:junit4]   1> 166627 [TEST-SolrExampleXMLTest.testChineseDefaults-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testChineseDefaults
[junit4:junit4]   1> 166660 [TEST-SolrExampleXMLTest.testAddRetrieve-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testAddRetrieve
[junit4:junit4]   1> 166668 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@57cf796c realtime
[junit4:junit4]   1> 166669 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435043810279161856)} 0 4
[junit4:junit4]   1> 166686 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1435043810295939072), id2 (1435043810299084800)]} 0 5
[junit4:junit4]   1> 181672 [commitScheduler-153-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> 181678 [commitScheduler-153-thread-1] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_f,generation=15}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_g,generation=16}
[junit4:junit4]   1> 181679 [commitScheduler-153-thread-1] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 16
[junit4:junit4]   1> 181683 [commitScheduler-153-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@32ef56b5 realtime
[junit4:junit4]   1> 181686 [commitScheduler-153-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 366881 [TEST-SolrExampleXMLTest.testAddRetrieve-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testAddRetrieve
[junit4:junit4]   1> 366890 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 366893 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_g,generation=16}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_h,generation=17}
[junit4:junit4]   1> 366896 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 17
[junit4:junit4]   1> 366897 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@74015702 main
[junit4:junit4]   1> 366899 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 366911 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@74015702 main{StandardDirectoryReader(segments_g:39:nrt _a(4.4):C2)}
[junit4:junit4]   1> 366911 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 366916 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@74015702 main{StandardDirectoryReader(segments_g:39:nrt _a(4.4):C2)}
[junit4:junit4]   1> 366960 [qtp1554147247-243] 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 70
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrExampleXMLTest -Dtests.method=testAddRetrieve -Dtests.seed=AF041A5DE31BB081 -Dtests.slow=true -Dtests.locale=en_IN -Dtests.timezone=America/Costa_Rica -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR    201s | SolrExampleXMLTest.testAddRetrieve <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:54187/solr/collection1
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([AF041A5DE31BB081:4CD78BFAD8A49A38]: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.commit(SolrServer.java:168)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrExampleTests.testAddRetrieve(SolrExampleTests.java:243)
[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> 367239 [TEST-SolrExampleXMLTest.testMultiContentStreamRequest-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testMultiContentStreamRequest
[junit4:junit4]   1> 367270 [qtp1554147247-245] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@37467a13 realtime
[junit4:junit4]   1> 367271 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044020625604608)} 0 4
[junit4:junit4]   1> 367281 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 367282 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_h,generation=17}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_i,generation=18}
[junit4:junit4]   1> 367283 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 18
[junit4:junit4]   1> 367306 [qtp1554147247-244] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@16120958 main
[junit4:junit4]   1> 367308 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 367309 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@16120958 main{StandardDirectoryReader(segments_h:41:nrt)}
[junit4:junit4]   1> 367310 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 367313 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@16120958 main{StandardDirectoryReader(segments_h:41:nrt)}
[junit4:junit4]   1> 367318 [qtp1554147247-244] 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 38
[junit4:junit4]   1> 367359 [qtp1554147247-248] 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> 367430 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 367450 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_i,generation=18}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_j,generation=19}
[junit4:junit4]   1> 367451 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 19
[junit4:junit4]   1> 367455 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@279d6908 main
[junit4:junit4]   1> 367457 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@279d6908 main{StandardDirectoryReader(segments_j:43:nrt _b(4.4):C5)}
[junit4:junit4]   1> 367458 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 367457 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 367461 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@279d6908 main{StandardDirectoryReader(segments_j:43:nrt _b(4.4):C5)}
[junit4:junit4]   1> 367463 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&echoParams=ALL&commit=true&a=?&wt=xml&version=2.2&softCommit=false} {add=[SP2514N (1435044020757725184), 6H500F0 (1435044020770308096), TWINX2048-3200PRO (1435044020784988160), VS1GB400C3 (1435044020789182464), VDBDB1A16 (1435044020793376768)],commit=} 0 71
[junit4:junit4]   1> 367471 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=5 status=0 QTime=1 
[junit4:junit4]   1> 367479 [TEST-SolrExampleXMLTest.testMultiContentStreamRequest-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testMultiContentStreamRequest
[junit4:junit4]   1> 367503 [TEST-SolrExampleXMLTest.testQueryWithParams-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testQueryWithParams
[junit4:junit4]   1> 367510 [qtp1554147247-245] 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=2 
[junit4:junit4]   1> 367516 [TEST-SolrExampleXMLTest.testQueryWithParams-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testQueryWithParams
[junit4:junit4]   1> 367544 [TEST-SolrExampleXMLTest.testPingHandler-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testPingHandler
[junit4:junit4]   1> 367552 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@48e55635 realtime
[junit4:junit4]   1> 367553 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044020921303040)} 0 4
[junit4:junit4]   1> 367561 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 367563 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_j,generation=19}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_k,generation=20}
[junit4:junit4]   1> 367564 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 20
[junit4:junit4]   1> 367566 [qtp1554147247-244] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4c92a6fb main
[junit4:junit4]   1> 367568 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 367569 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@4c92a6fb main{StandardDirectoryReader(segments_j:45:nrt)}
[junit4:junit4]   1> 367569 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 367573 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@4c92a6fb main{StandardDirectoryReader(segments_j:45:nrt)}
[junit4:junit4]   1> 367574 [qtp1554147247-244] 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> 367583 [qtp1554147247-248] 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> 367590 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/admin/ping params={wt=xml&version=2.2} hits=0 status=0 QTime=1 
[junit4:junit4]   1> 367591 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/admin/ping params={wt=xml&version=2.2} status=0 QTime=2 
[junit4:junit4]   1> 367594 [TEST-SolrExampleXMLTest.testPingHandler-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testPingHandler
[junit4:junit4]   1> 367654 [TEST-SolrExampleXMLTest.testExampleConfig-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testExampleConfig
[junit4:junit4]   1> 367669 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044021043986432)} 0 4
[junit4:junit4]   1> 367684 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1112211111 (1435044021061812224)]} 0 1
[junit4:junit4]   1> 367701 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 367707 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_k,generation=20}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_l,generation=21}
[junit4:junit4]   1> 367708 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 21
[junit4:junit4]   1> 367711 [qtp1554147247-244] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@31966bd8 main
[junit4:junit4]   1> 367713 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@31966bd8 main{StandardDirectoryReader(segments_l:47:nrt _c(4.4):C1)}
[junit4:junit4]   1> 367713 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 367714 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 367718 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@31966bd8 main{StandardDirectoryReader(segments_l:47:nrt _c(4.4):C1)}
[junit4:junit4]   1> 367719 [qtp1554147247-244] 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> 367730 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 367733 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_l,generation=21}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_m,generation=22}
[junit4:junit4]   1> 367734 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 22
[junit4:junit4]   1> 367735 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@76321db8 main
[junit4:junit4]   1> 367737 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 367738 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@76321db8 main{StandardDirectoryReader(segments_l:47:nrt _c(4.4):C1)}
[junit4:junit4]   1> 367739 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 367743 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@76321db8 main{StandardDirectoryReader(segments_l:47:nrt _c(4.4):C1)}
[junit4:junit4]   1> 367745 [qtp1554147247-248] 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 15
[junit4:junit4]   1> 367755 [qtp1554147247-243] 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> 367788 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 (1435044021155135488), 3 (1435044021159329792), 4 (1435044021162475520), 5 (1435044021170864128)]} 0 16
[junit4:junit4]   1> 367796 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 367805 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_m,generation=22}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_n,generation=23}
[junit4:junit4]   1> 367806 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 23
[junit4:junit4]   1> 367862 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@71a75377 main
[junit4:junit4]   1> 367864 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 367865 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@71a75377 main{StandardDirectoryReader(segments_n:49:nrt _c(4.4):C1 _d(4.4):C4)}
[junit4:junit4]   1> 367865 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 367869 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@71a75377 main{StandardDirectoryReader(segments_n:49:nrt _c(4.4):C1 _d(4.4):C4)}
[junit4:junit4]   1> 367871 [qtp1554147247-247] 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 75
[junit4:junit4]   1> 367891 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 367899 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_n,generation=23}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_o,generation=24}
[junit4:junit4]   1> 367899 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 24
[junit4:junit4]   1> 367909 [qtp1554147247-245] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@79e472f2 main
[junit4:junit4]   1> 367910 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 367911 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@79e472f2 main{StandardDirectoryReader(segments_o:51:nrt _e(4.4):C5)}
[junit4:junit4]   1> 367911 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 367913 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@79e472f2 main{StandardDirectoryReader(segments_o:51:nrt _e(4.4):C5)}
[junit4:junit4]   1> 367914 [qtp1554147247-245] 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 23
[junit4:junit4]   1> 367963 [qtp1554147247-248] 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=4 
[junit4:junit4]   1> 367989 [qtp1554147247-243] 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=9 
[junit4:junit4]   1> 368008 [qtp1554147247-247] 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=4 
[junit4:junit4]   1> 368013 [TEST-SolrExampleXMLTest.testExampleConfig-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testExampleConfig
[junit4:junit4]   1> 368058 [TEST-SolrExampleXMLTest.testCommitWithinOnDelete-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testCommitWithinOnDelete
[junit4:junit4]   1> 368068 [qtp1554147247-245] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6931ae4c realtime
[junit4:junit4]   1> 368069 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044021462368256)} 0 5
[junit4:junit4]   1> 368078 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 368080 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_o,generation=24}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_p,generation=25}
[junit4:junit4]   1> 368081 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 25
[junit4:junit4]   1> 368082 [qtp1554147247-244] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3a4d75b2 main
[junit4:junit4]   1> 368084 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 368085 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3a4d75b2 main{StandardDirectoryReader(segments_o:53:nrt)}
[junit4:junit4]   1> 368086 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 368097 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3a4d75b2 main{StandardDirectoryReader(segments_o:53:nrt)}
[junit4:junit4]   1> 368099 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 21
[junit4:junit4]   1> 368107 [qtp1554147247-246] 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> 368127 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id3 (1435044021525282816)]} 0 2
[junit4:junit4]   1> 368136 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 368142 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_p,generation=25}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_q,generation=26}
[junit4:junit4]   1> 368142 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 26
[junit4:junit4]   1> 368153 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@aa63276 main
[junit4:junit4]   1> 368155 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 368155 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@aa63276 main{StandardDirectoryReader(segments_q:55:nrt _f(4.4):C1)}
[junit4:junit4]   1> 368156 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 368159 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@aa63276 main{StandardDirectoryReader(segments_q:55:nrt _f(4.4):C1)}
[junit4:junit4]   1> 368160 [qtp1554147247-247] 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 24
[junit4:junit4]   1> 368179 [qtp1554147247-245] 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> 368220 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[id3 (-1435044021621751808)]} 0 3
[junit4:junit4]   1> 368235 [qtp1554147247-248] 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> 368496 [qtp1554147247-246] 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> 368783 [qtp1554147247-243] 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=25 
[junit4:junit4]   1> 369043 [qtp1554147247-245] 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> 369220 [commitScheduler-154-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> 369222 [commitScheduler-154-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@71e8d537 main
[junit4:junit4]   1> 369223 [commitScheduler-154-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 369223 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@71e8d537 main{StandardDirectoryReader(segments_q:57:nrt)}
[junit4:junit4]   1> 369224 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 369227 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@71e8d537 main{StandardDirectoryReader(segments_q:57:nrt)}
[junit4:junit4]   1> 369308 [qtp1554147247-244] 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> 369313 [TEST-SolrExampleXMLTest.testCommitWithinOnDelete-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testCommitWithinOnDelete
[junit4:junit4]   1> 369353 [TEST-SolrExampleXMLTest.testUpdateField-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testUpdateField
[junit4:junit4]   1> 369360 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044022819225600)} 0 1
[junit4:junit4]   1> 369370 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 369399 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_q,generation=26}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_r,generation=27}
[junit4:junit4]   1> 369401 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 27
[junit4:junit4]   1> 369403 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@119d9bf2 main
[junit4:junit4]   1> 369405 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@119d9bf2 main{StandardDirectoryReader(segments_q:57:nrt)}
[junit4:junit4]   1> 369405 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 369409 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@119d9bf2 main{StandardDirectoryReader(segments_q:57:nrt)}
[junit4:junit4]   1> 369405 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 369410 [qtp1554147247-243] 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> 369424 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1435044022884237312)]} 0 3
[junit4:junit4]   1> 369438 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 369444 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_r,generation=27}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_s,generation=28}
[junit4:junit4]   1> 369445 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 28
[junit4:junit4]   1> 369455 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@54dfec91 main
[junit4:junit4]   1> 369457 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 369458 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@54dfec91 main{StandardDirectoryReader(segments_s:59:nrt _g(4.4):C1)}
[junit4:junit4]   1> 369459 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 369467 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@54dfec91 main{StandardDirectoryReader(segments_s:59:nrt _g(4.4):C1)}
[junit4:junit4]   1> 369470 [qtp1554147247-248] 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 32
[junit4:junit4]   1> 369477 [qtp1554147247-244] 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> 369486 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 1
[junit4:junit4]   1> 369498 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1435044022961831936)]} 0 4
[junit4:junit4]   1> 369507 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 369515 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_s,generation=28}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_t,generation=29}
[junit4:junit4]   1> 369516 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 29
[junit4:junit4]   1> 369519 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@30bad9de main
[junit4:junit4]   1> 369521 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 369522 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@30bad9de main{StandardDirectoryReader(segments_t:63:nrt _h(4.4):C1)}
[junit4:junit4]   1> 369525 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 369541 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@30bad9de main{StandardDirectoryReader(segments_t:63:nrt _h(4.4):C1)}
[junit4:junit4]   1> 369543 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 36
[junit4:junit4]   1> 369551 [qtp1554147247-245] 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> 369564 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1435044023031037952)]} 0 4
[junit4:junit4]   1> 369575 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 369579 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_t,generation=29}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_u,generation=30}
[junit4:junit4]   1> 369580 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 30
[junit4:junit4]   1> 369583 [qtp1554147247-244] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@21e6a734 main
[junit4:junit4]   1> 369584 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@21e6a734 main{StandardDirectoryReader(segments_u:67:nrt _i(4.4):C1)}
[junit4:junit4]   1> 369584 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 369586 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@21e6a734 main{StandardDirectoryReader(segments_u:67:nrt _i(4.4):C1)}
[junit4:junit4]   1> 369592 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 369594 [qtp1554147247-244] 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> 369601 [qtp1554147247-246] 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> 369604 [TEST-SolrExampleXMLTest.testUpdateField-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testUpdateField
[junit4:junit4]   1> 369623 [TEST-SolrExampleXMLTest.testUnicode-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testUnicode
[junit4:junit4]   1> 369633 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3c2b6aec realtime
[junit4:junit4]   1> 369635 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1435044023104438272)} 0 4
[junit4:junit4]   1> 369654 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1435044023122264064), 1 (1435044023123312640), 2 (1435044023124361216), 3 (1435044023124361217), 4 (1435044023125409792), 5 (1435044023126458368), 6 (1435044023127506944)]} 0 7
[junit4:junit4]   1> 369667 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 369671 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_u,generation=30}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_v,generation=31}
[junit4:junit4]   1> 369671 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 31
[junit4:junit4]   1> 369673 [qtp1554147247-245] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@24fc544f main
[junit4:junit4]   1> 369675 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@24fc544f main{StandardDirectoryReader(segments_v:71:nrt _j(4.4):C7)}
[junit4:junit4]   1> 369676 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 369676 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 369680 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@24fc544f main{StandardDirectoryReader(segments_v:71:nrt _j(4.4):C7)}
[junit4:junit4]   1> 369682 [qtp1554147247-245] 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 15
[junit4:junit4]   1> 369690 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=7} hits=7 status=0 QTime=1 
[junit4:junit4]   1> 369702 [qtp1554147247-244] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@62f3dce4 realtime
[junit4:junit4]   1> 369703 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1435044023175741440)} 0 4
[junit4:junit4]   1> 369739 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1435044023214538752), 1 (1435044023215587328), 2 (1435044023216635904), 3 (1435044023216635905), 4 (1435044023217684480)]} 0 3
[junit4:junit4]   1> 369746 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 369750 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_v,generation=31}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_w,generation=32}
[junit4:junit4]   1> 369751 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 32
[junit4:junit4]   1> 369754 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3abf4c37 main
[junit4:junit4]   1> 369756 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3abf4c37 main{StandardDirectoryReader(segments_w:75:nrt _k(4.4):C5)}
[junit4:junit4]   1> 369757 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 369805 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 369807 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3abf4c37 main{StandardDirectoryReader(segments_w:75:nrt _k(4.4):C5)}
[junit4:junit4]   1> 369808 [qtp1554147247-247] 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 63
[junit4:junit4]   1> 369826 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=5} hits=5 status=0 QTime=1 
[junit4:junit4]   1> 369838 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@ec9dba6 realtime
[junit4:junit4]   1> 369839 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1435044023318347776)} 0 5
[junit4:junit4]   1> 369856 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1435044023335124992), 1 (1435044023337222144), 2 (1435044023337222145), 3 (1435044023338270720), 4 (1435044023339319296), 5 (1435044023339319297)]} 0 5
[junit4:junit4]   1> 369865 [qtp1554147247-246] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 369875 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_w,generation=32}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_x,generation=33}
[junit4:junit4]   1> 369879 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 33
[junit4:junit4]   1> 369882 [qtp1554147247-246] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7c16a85a main
[junit4:junit4]   1> 369885 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@7c16a85a main{StandardDirectoryReader(segments_x:79:nrt _l(4.4):C6)}
[junit4:junit4]   1> 369885 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 369889 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@7c16a85a main{StandardDirectoryReader(segments_x:79:nrt _l(4.4):C6)}
[junit4:junit4]   1> 369891 [qtp1554147247-246] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 369891 [qtp1554147247-246] 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 27
[junit4:junit4]   1> 369900 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=6} hits=6 status=0 QTime=1 
[junit4:junit4]   1> 369904 [TEST-SolrExampleXMLTest.testUnicode-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testUnicode
[junit4:junit4]   1> 369938 [TEST-SolrExampleXMLTest.testSetNullUpdateOrder-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testSetNullUpdateOrder
[junit4:junit4]   1> 369950 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdateOrder (1435044023436836864)]} 0 3
[junit4:junit4]   1> 369958 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 369963 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_x,generation=33}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_y,generation=34}
[junit4:junit4]   1> 369964 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 34
[junit4:junit4]   1> 369967 [qtp1554147247-245] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@58b47499 main
[junit4:junit4]   1> 369970 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 369970 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@58b47499 main{StandardDirectoryReader(segments_y:81:nrt _l(4.4):C6 _m(4.4):C1)}
[junit4:junit4]   1> 369971 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 369974 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@58b47499 main{StandardDirectoryReader(segments_y:81:nrt _l(4.4):C6 _m(4.4):C1)}
[junit4:junit4]   1> 369975 [qtp1554147247-245] 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> 369986 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdateOrder (1435044023473537024)]} 0 3
[junit4:junit4]   1> 370013 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 370019 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_y,generation=34}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_z,generation=35}
[junit4:junit4]   1> 370020 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 35
[junit4:junit4]   1> 370024 [qtp1554147247-244] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7273e4db main
[junit4:junit4]   1> 370026 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@7273e4db main{StandardDirectoryReader(segments_z:85:nrt _l(4.4):C6 _n(4.4):C1)}
[junit4:junit4]   1> 370027 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 370034 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@7273e4db main{StandardDirectoryReader(segments_z:85:nrt _l(4.4):C6 _n(4.4):C1)}
[junit4:junit4]   1> 370042 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 370045 [qtp1554147247-244] 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 32
[junit4:junit4]   1> 370077 [qtp1554147247-246] 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> 370082 [TEST-SolrExampleXMLTest.testSetNullUpdateOrder-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testSetNullUpdateOrder
[junit4:junit4]   1> 370123 [TEST-SolrExampleXMLTest.testAddDelete-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testAddDelete
[junit4:junit4]   1> 370138 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@4f45bcbc realtime
[junit4:junit4]   1> 370139 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044023631872000)} 0 5
[junit4:junit4]   1> 370150 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1435044023647600640)]} 0 2
[junit4:junit4]   1> 370162 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 370166 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_z,generation=35}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_10,generation=36}
[junit4:junit4]   1> 370167 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 36
[junit4:junit4]   1> 370170 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@1f264bd6 main
[junit4:junit4]   1> 370173 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@1f264bd6 main{StandardDirectoryReader(segments_10:89:nrt _o(4.4):C1)}
[junit4:junit4]   1> 370174 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 370177 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@1f264bd6 main{StandardDirectoryReader(segments_10:89:nrt _o(4.4):C1)}
[junit4:junit4]   1> 370173 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 370179 [qtp1554147247-248] 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> 370194 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   1> 370204 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1435044023704223744)]} 0 2
[junit4:junit4]   1> 370215 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 370218 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_10,generation=36}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_11,generation=37}
[junit4:junit4]   1> 370219 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 37
[junit4:junit4]   1> 370221 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@106de296 main
[junit4:junit4]   1> 370223 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 370223 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@106de296 main{StandardDirectoryReader(segments_11:91:nrt)}
[junit4:junit4]   1> 370224 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 370229 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@106de296 main{StandardDirectoryReader(segments_11:91:nrt)}
[junit4:junit4]   1> 370232 [qtp1554147247-247] 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> 370243 [qtp1554147247-243] 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> 370258 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1435044023758749696)]} 0 4
[junit4:junit4]   1> 370283 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 370288 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_11,generation=37}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_12,generation=38}
[junit4:junit4]   1> 370289 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 38
[junit4:junit4]   1> 370291 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6993baa9 main
[junit4:junit4]   1> 370293 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6993baa9 main{StandardDirectoryReader(segments_12:93:nrt _p(4.4):C1)}
[junit4:junit4]   1> 370294 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 370294 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 370297 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6993baa9 main{StandardDirectoryReader(segments_12:93:nrt _p(4.4):C1)}
[junit4:junit4]   1> 370298 [qtp1554147247-248] 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> 370307 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=0 
[junit4:junit4]   1> 370347 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5e381106 realtime
[junit4:junit4]   1> 370348 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=id:"0\ \&\ 222" (-1435044023839490048)} 0 16
[junit4:junit4]   1> 370387 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 370391 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_12,generation=38}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_13,generation=39}
[junit4:junit4]   1> 370392 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 39
[junit4:junit4]   1> 370393 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2673beef main
[junit4:junit4]   1> 370395 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@2673beef main{StandardDirectoryReader(segments_12:95:nrt)}
[junit4:junit4]   1> 370395 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 370397 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 370411 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@2673beef main{StandardDirectoryReader(segments_12:95:nrt)}
[junit4:junit4]   1> 370415 [qtp1554147247-243] 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> 370427 [qtp1554147247-245] 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> 370440 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1435044023947493376)]} 0 5
[junit4:junit4]   1> 370472 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 & 222 (1435044023985242112)]} 0 2
[junit4:junit4]   1> 370492 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 & 222 (1435044024006213632)]} 0 2
[junit4:junit4]   1> 370515 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 370530 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_13,generation=39}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_14,generation=40}
[junit4:junit4]   1> 370532 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 40
[junit4:junit4]   1> 370535 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@3c1d7aa4 main
[junit4:junit4]   1> 370538 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 370540 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@3c1d7aa4 main{StandardDirectoryReader(segments_14:97:nrt _q(4.4):C3)}
[junit4:junit4]   1> 370540 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 370545 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@3c1d7aa4 main{StandardDirectoryReader(segments_14:97:nrt _q(4.4):C3)}
[junit4:junit4]   1> 370547 [qtp1554147247-243] 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 32
[junit4:junit4]   1> 370560 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=3 status=0 QTime=1 
[junit4:junit4]   1> 370571 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1435044024086953984), 1 & 222 (-1435044024089051136), 2 & 222 (-1435044024089051137)]} 0 3
[junit4:junit4]   1> 370586 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 370589 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_14,generation=40}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_15,generation=41}
[junit4:junit4]   1> 370590 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 41
[junit4:junit4]   1> 370591 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@381c31e0 main
[junit4:junit4]   1> 370593 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 370595 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@381c31e0 main{StandardDirectoryReader(segments_15:99:nrt)}
[junit4:junit4]   1> 370595 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 370598 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@381c31e0 main{StandardDirectoryReader(segments_15:99:nrt)}
[junit4:junit4]   1> 370600 [qtp1554147247-247] 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> 370610 [qtp1554147247-243] 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> 370614 [TEST-SolrExampleXMLTest.testAddDelete-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testAddDelete
[junit4:junit4]   1> 370645 [TEST-SolrExampleXMLTest.testPivotFacetsMissing-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testPivotFacetsMissing
[junit4:junit4]   1> 370671 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044024193908736)} 0 2
[junit4:junit4]   1> 370679 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 370680 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_15,generation=41}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_16,generation=42}
[junit4:junit4]   1> 370681 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 42
[junit4:junit4]   1> 370682 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7b77c3e7 main
[junit4:junit4]   1> 370684 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 370685 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@7b77c3e7 main{StandardDirectoryReader(segments_15:99:nrt)}
[junit4:junit4]   1> 370685 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 370687 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@7b77c3e7 main{StandardDirectoryReader(segments_15:99:nrt)}
[junit4:junit4]   1> 370688 [qtp1554147247-248] 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> 370696 [qtp1554147247-244] 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> 370722 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1435044024229560320), 2 (1435044024232706048), 3 (1435044024233754624), 4 (1435044024233754625), 5 (1435044024234803200), 6 (1435044024235851776), 7 (1435044024236900352), 8 (1435044024237948928), 9 (1435044024244240384), 10 (1435044024245288960), ... (12 adds)]} 0 18
[junit4:junit4]   1> 370730 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 370742 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_16,generation=42}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_17,generation=43}
[junit4:junit4]   1> 370744 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 43
[junit4:junit4]   1> 370746 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@77d95d4d main
[junit4:junit4]   1> 370749 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 370750 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@77d95d4d main{StandardDirectoryReader(segments_17:101:nrt _r(4.4):C12)}
[junit4:junit4]   1> 370750 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 370754 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@77d95d4d main{StandardDirectoryReader(segments_17:101:nrt _r(4.4):C12)}
[junit4:junit4]   1> 370755 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 25
[junit4:junit4]   1> 370765 [qtp1554147247-243] 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> 370767 [qtp1554147247-243] 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> 370771 [qtp1554147247-243] 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=7 
[junit4:junit4]   1> 370797 [qtp1554147247-245] 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=4 
[junit4:junit4]   1> 370802 [TEST-SolrExampleXMLTest.testPivotFacetsMissing-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testPivotFacetsMissing
[junit4:junit4]   1> 370850 [TEST-SolrExampleXMLTest.testSetNullUpdates-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testSetNullUpdates
[junit4:junit4]   1> 370861 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdates (1435044024391041024)]} 0 4
[junit4:junit4]   1> 370877 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 370884 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_17,generation=43}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_18,generation=44}
[junit4:junit4]   1> 370886 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 44
[junit4:junit4]   1> 370889 [qtp1554147247-244] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@49826ea3 main
[junit4:junit4]   1> 370891 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@49826ea3 main{StandardDirectoryReader(segments_18:103:nrt _r(4.4):C12 _s(4.4):C1)}
[junit4:junit4]   1> 370892 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 370895 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@49826ea3 main{StandardDirectoryReader(segments_18:103:nrt _r(4.4):C12 _s(4.4):C1)}
[junit4:junit4]   1> 370897 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 370898 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 21
[junit4:junit4]   1> 370910 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdates (1435044024442421248)]} 0 3
[junit4:junit4]   1> 370918 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 370929 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_18,generation=44}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_19,generation=45}
[junit4:junit4]   1> 370930 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 45
[junit4:junit4]   1> 370933 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@e15929b main
[junit4:junit4]   1> 370934 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@e15929b main{StandardDirectoryReader(segments_19:107:nrt _r(4.4):C12 _t(4.4):C1)}
[junit4:junit4]   1> 370935 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 370937 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 370939 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@e15929b main{StandardDirectoryReader(segments_19:107:nrt _r(4.4):C12 _t(4.4):C1)}
[junit4:junit4]   1> 370941 [qtp1554147247-247] 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> 370958 [qtp1554147247-243] 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> 370962 [TEST-SolrExampleXMLTest.testSetNullUpdates-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testSetNullUpdates
[junit4:junit4]   1> 370981 [TEST-SolrExampleXMLTest.testUpdateRequestWithParameters-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testUpdateRequestWithParameters
[junit4:junit4]   1> 370982 [TEST-SolrExampleXMLTest.testUpdateRequestWithParameters-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.client.solrj.impl.HttpClientUtil  ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> 371077 [qtp1554147247-246] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6639387f realtime
[junit4:junit4]   1> 371078 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044024617533440)} 0 5
[junit4:junit4]   1> 371123 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 371125 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_19,generation=45}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1a,generation=46}
[junit4:junit4]   1> 371126 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 46
[junit4:junit4]   1> 371134 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@642c46fb main
[junit4:junit4]   1> 371136 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@642c46fb main{StandardDirectoryReader(segments_19:109:nrt)}
[junit4:junit4]   1> 371136 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 371137 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 371141 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@642c46fb main{StandardDirectoryReader(segments_19:109:nrt)}
[junit4:junit4]   1> 371142 [qtp1554147247-247] 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> 371152 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=xml&version=2.2} {add=[id1 (1435044024697225216)]} 0 2
[junit4:junit4]   1> 371162 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=xml&version=2.2} {add=[id1 (1435044024708759552)]} 0 1
[junit4:junit4]   1> 371170 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 371173 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1a,generation=46}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1b,generation=47}
[junit4:junit4]   1> 371174 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 47
[junit4:junit4]   1> 371176 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7c20a9de main
[junit4:junit4]   1> 371181 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 371182 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@7c20a9de main{StandardDirectoryReader(segments_1b:111:nrt _u(4.4):C2)}
[junit4:junit4]   1> 371182 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 371187 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@7c20a9de main{StandardDirectoryReader(segments_1b:111:nrt _u(4.4):C2)}
[junit4:junit4]   1> 371189 [qtp1554147247-248] 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> 371201 [qtp1554147247-244] 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> 371205 [TEST-SolrExampleXMLTest.testUpdateRequestWithParameters-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testUpdateRequestWithParameters
[junit4:junit4]   1> 371224 [TEST-SolrExampleXMLTest.testPivotFacets-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testPivotFacets
[junit4:junit4]   1> 371230 [qtp1554147247-246] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@67409450 realtime
[junit4:junit4]   1> 371231 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044024779014144)} 0 3
[junit4:junit4]   1> 371237 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 371238 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1b,generation=47}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1c,generation=48}
[junit4:junit4]   1> 371238 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 48
[junit4:junit4]   1> 371239 [qtp1554147247-247] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@23776376 main
[junit4:junit4]   1> 371240 [qtp1554147247-247] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 371241 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@23776376 main{StandardDirectoryReader(segments_1b:113:nrt)}
[junit4:junit4]   1> 371242 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 371246 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@23776376 main{StandardDirectoryReader(segments_1b:113:nrt)}
[junit4:junit4]   1> 371247 [qtp1554147247-247] 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> 371261 [qtp1554147247-243] 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> 371298 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1435044024825151488), 2 (1435044024835637248), 3 (1435044024837734400), 4 (1435044024838782976), 5 (1435044024839831552), 6 (1435044024840880128), 7 (1435044024842977280), 8 (1435044024845074432), 9 (1435044024846123008), 10 (1435044024848220160), ... (12 adds)]} 0 26
[junit4:junit4]   1> 371307 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 371313 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1c,generation=48}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1d,generation=49}
[junit4:junit4]   1> 371314 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 49
[junit4:junit4]   1> 371317 [qtp1554147247-244] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5ea0f390 main
[junit4:junit4]   1> 371319 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5ea0f390 main{StandardDirectoryReader(segments_1d:115:nrt _v(4.4):C12)}
[junit4:junit4]   1> 371319 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 371331 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5ea0f390 main{StandardDirectoryReader(segments_1d:115:nrt _v(4.4):C12)}
[junit4:junit4]   1> 371331 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 371333 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 26
[junit4:junit4]   1> 371341 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=1,phase1=1,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4]   1> 371342 [qtp1554147247-246] 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> 371345 [qtp1554147247-246] 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=6 
[junit4:junit4]   1> 371361 [qtp1554147247-247] 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=4 
[junit4:junit4]   1> 371366 [TEST-SolrExampleXMLTest.testPivotFacets-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testPivotFacets
[junit4:junit4]   1> 371405 [TEST-SolrExampleXMLTest.testCommitWithinOnAdd-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testCommitWithinOnAdd
[junit4:junit4]   1> 371416 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@110f873b realtime
[junit4:junit4]   1> 371420 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044024969854976)} 0 10
[junit4:junit4]   1> 371428 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 371429 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1d,generation=49}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1e,generation=50}
[junit4:junit4]   1> 371430 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 50
[junit4:junit4]   1> 371433 [qtp1554147247-245] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@96d1e89 main
[junit4:junit4]   1> 371436 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@96d1e89 main{StandardDirectoryReader(segments_1d:117:nrt)}
[junit4:junit4]   1> 371437 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 371437 [qtp1554147247-245] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 371440 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@96d1e89 main{StandardDirectoryReader(segments_1d:117:nrt)}
[junit4:junit4]   1> 371442 [qtp1554147247-245] 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> 371451 [qtp1554147247-244] 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> 371465 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id3 (1435044025023332352)]} 0 4
[junit4:junit4]   1> 371475 [qtp1554147247-247] 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> 371967 [commitScheduler-154-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> 371979 [commitScheduler-154-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@186a047a main
[junit4:junit4]   1> 371980 [commitScheduler-154-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 371980 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@186a047a main{StandardDirectoryReader(segments_1e:119:nrt _w(4.4):C1)}
[junit4:junit4]   1> 371981 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 371993 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@186a047a main{StandardDirectoryReader(segments_1e:119:nrt _w(4.4):C1)}
[junit4:junit4]   1> 372484 [qtp1554147247-245] 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> 372500 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id4 (1435044026110705664)]} 0 2
[junit4:junit4]   1> 373002 [commitScheduler-154-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> 373012 [commitScheduler-154-thread-1] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@7cecbe46 main
[junit4:junit4]   1> 373013 [commitScheduler-154-thread-1] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 373014 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@7cecbe46 main{StandardDirectoryReader(segments_1e:121:nrt _w(4.4):C1 _x(4.4):C1)}
[junit4:junit4]   1> 373015 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 373020 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@7cecbe46 main{StandardDirectoryReader(segments_1e:121:nrt _w(4.4):C1 _x(4.4):C1)}
[junit4:junit4]   1> 373509 [qtp1554147247-243] 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=0 
[junit4:junit4]   1> 373514 [TEST-SolrExampleXMLTest.testCommitWithinOnAdd-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testCommitWithinOnAdd
[junit4:junit4]   1> 373530 [TEST-SolrExampleXMLTest.testRealtimeGet-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testRealtimeGet
[junit4:junit4]   1> 373538 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@35e69a3d realtime
[junit4:junit4]   1> 373540 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044027198078976)} 0 5
[junit4:junit4]   1> 373558 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[DOCID (1435044027221147648)]} 0 2
[junit4:junit4]   1> 373565 [qtp1554147247-246] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 373571 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1e,generation=50}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1f,generation=51}
[junit4:junit4]   1> 373572 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 51
[junit4:junit4]   1> 373575 [qtp1554147247-246] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@6e7113f0 main
[junit4:junit4]   1> 373578 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@6e7113f0 main{StandardDirectoryReader(segments_1f:125:nrt _y(4.4):C1)}
[junit4:junit4]   1> 373578 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 373579 [qtp1554147247-246] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 373581 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@6e7113f0 main{StandardDirectoryReader(segments_1f:125:nrt _y(4.4):C1)}
[junit4:junit4]   1> 373597 [qtp1554147247-246] 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 32
[junit4:junit4]   1> 373646 [qtp1554147247-247] 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=1 
[junit4:junit4]   1> 373663 [qtp1554147247-243] 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=1 
[junit4:junit4]   1> 373670 [TEST-SolrExampleXMLTest.testRealtimeGet-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testRealtimeGet
[junit4:junit4]   1> 373699 [TEST-SolrExampleXMLTest.testFaceting-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testFaceting
[junit4:junit4]   1> 373706 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2538b004 realtime
[junit4:junit4]   1> 373707 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044027375288320)} 0 4
[junit4:junit4]   1> 373714 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 373715 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1f,generation=51}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1g,generation=52}
[junit4:junit4]   1> 373716 [qtp1554147247-244] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 52
[junit4:junit4]   1> 373717 [qtp1554147247-244] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@2f73661c main
[junit4:junit4]   1> 373719 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@2f73661c main{StandardDirectoryReader(segments_1f:127:nrt)}
[junit4:junit4]   1> 373720 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 373737 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@2f73661c main{StandardDirectoryReader(segments_1f:127:nrt)}
[junit4:junit4]   1> 373740 [qtp1554147247-244] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 373741 [qtp1554147247-244] 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 27
[junit4:junit4]   1> 373756 [qtp1554147247-246] 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> 373800 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1435044027465465856), 2 (1435044027467563008), 3 (1435044027469660160), 4 (1435044027469660161), 5 (1435044027470708736), 6 (1435044027471757312), 7 (1435044027472805888), 8 (1435044027473854464), 9 (1435044027474903040), 10 (1435044027474903041)]} 0 11
[junit4:junit4]   1> 373809 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 373815 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1g,generation=52}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1h,generation=53}
[junit4:junit4]   1> 373816 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 53
[junit4:junit4]   1> 373820 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@5798fe78 main
[junit4:junit4]   1> 373822 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@5798fe78 main{StandardDirectoryReader(segments_1h:129:nrt _z(4.4):C10)}
[junit4:junit4]   1> 373822 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 373826 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@5798fe78 main{StandardDirectoryReader(segments_1h:129:nrt _z(4.4):C10)}
[junit4:junit4]   1> 373822 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 373828 [qtp1554147247-243] 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> 373838 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=0,phase1=0,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4:junit4]   1> 373839 [qtp1554147247-248] 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=2 
[junit4:junit4]   1> 373848 [qtp1554147247-245] 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=1 
[junit4:junit4]   1> 373867 [qtp1554147247-246] 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=1 
[junit4:junit4]   1> 373878 [qtp1554147247-247] 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=1 
[junit4:junit4]   1> 373888 [TEST-SolrExampleXMLTest.testFaceting-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testFaceting
[junit4:junit4]   1> 374008 [TEST-SolrExampleXMLTest.testStatistics-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Starting testStatistics
[junit4:junit4]   1> 374017 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@1ef21a23 realtime
[junit4:junit4]   1> 374018 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1435044027700346880)} 0 4
[junit4:junit4]   1> 374031 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 374033 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1h,generation=53}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1i,generation=54}
[junit4:junit4]   1> 374034 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 54
[junit4:junit4]   1> 374037 [qtp1554147247-248] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@63e266df main
[junit4:junit4]   1> 374040 [qtp1554147247-248] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 374041 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@63e266df main{StandardDirectoryReader(segments_1h:131:nrt)}
[junit4:junit4]   1> 374042 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 374046 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@63e266df main{StandardDirectoryReader(segments_1h:131:nrt)}
[junit4:junit4]   1> 374047 [qtp1554147247-248] 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> 374062 [qtp1554147247-245] 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> 374087 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc0 (1435044027771650048)]} 0 5
[junit4:junit4]   1> 374105 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc1 (1435044027793670144)]} 0 2
[junit4:junit4]   1> 374113 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc2 (1435044027803107328)]} 0 2
[junit4:junit4]   1> 374125 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc3 (1435044027814641664)]} 0 2
[junit4:junit4]   1> 374147 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc4 (1435044027837710336)]} 0 2
[junit4:junit4]   1> 374162 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc5 (1435044027853438976)]} 0 2
[junit4:junit4]   1> 374177 [qtp1554147247-243] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc6 (1435044027870216192)]} 0 2
[junit4:junit4]   1> 374199 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc7 (1435044027892236288)]} 0 2
[junit4:junit4]   1> 374210 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc8 (1435044027904819200)]} 0 2
[junit4:junit4]   1> 374238 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc9 (1435044027932082176)]} 0 3
[junit4:junit4]   1> 374275 [qtp1554147247-246] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 374372 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1i,generation=54}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1j,generation=55}
[junit4:junit4]   1> 374373 [qtp1554147247-246] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 55
[junit4:junit4]   1> 374376 [qtp1554147247-246] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@69262cfa main
[junit4:junit4]   1> 374378 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sendin

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


[junit4:junit4]   1> 374976 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc3 (1435044028708028416)]} 0 1
[junit4:junit4]   1> 375009 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc4 (1435044028742631424)]} 0 1
[junit4:junit4]   1> 375025 [qtp1554147247-247] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc5 (1435044028758360064)]} 0 2
[junit4:junit4]   1> 375041 [qtp1554147247-248] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc6 (1435044028776185856)]} 0 1
[junit4:junit4]   1> 375049 [qtp1554147247-245] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc7 (1435044028784574464)]} 0 1
[junit4:junit4]   1> 375057 [qtp1554147247-244] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc8 (1435044028792963072)]} 0 2
[junit4:junit4]   1> 375063 [qtp1554147247-246] INFO  org.apache.solr.update.processor.LogUpdateProcessor  ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc9 (1435044028799254528)]} 0 1
[junit4:junit4]   1> 375069 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> 375073 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1m,generation=58}
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11a41324 lockFactory=org.apache.lucene.store.NativeFSLockFactory@6d00c844),segFN=segments_1n,generation=59}
[junit4:junit4]   1> 375074 [qtp1554147247-243] INFO  org.apache.solr.core.SolrCore  ? newest commit generation = 59
[junit4:junit4]   1> 375076 [qtp1554147247-243] INFO  org.apache.solr.search.SolrIndexSearcher  ? Opening Searcher@491409f2 main
[junit4:junit4]   1> 375079 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener sending requests to Searcher@491409f2 main{StandardDirectoryReader(segments_1n:141:nrt _12(4.4):C10)}
[junit4:junit4]   1> 375081 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? QuerySenderListener done.
[junit4:junit4]   1> 375085 [qtp1554147247-243] INFO  org.apache.solr.update.UpdateHandler  ? end_commit_flush
[junit4:junit4]   1> 375087 [searcherExecutor-151-thread-1] INFO  org.apache.solr.core.SolrCore  ? [collection1] Registered new searcher Searcher@491409f2 main{StandardDirectoryReader(segments_1n:141:nrt _12(4.4):C10)}
[junit4:junit4]   1> 375088 [qtp1554147247-243] 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> 375096 [qtp1554147247-247] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=inStock:true&wt=xml&version=2.2} hits=5 status=0 QTime=1 
[junit4:junit4]   1> 375104 [qtp1554147247-248] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={q=inStock:false&wt=xml&version=2.2} hits=5 status=0 QTime=0 
[junit4:junit4]   1> 375113 [qtp1554147247-245] INFO  org.apache.solr.core.SolrCore  ? [collection1] webapp=/solr path=/select params={f.val_pi.stats.facet=inStock&stats.field=val_pi&stats=true&q=*:*&wt=xml&version=2.2&rows=0} hits=10 status=0 QTime=1 
[junit4:junit4]   1> 375119 [TEST-SolrExampleXMLTest.testStatistics-seed#[AF041A5DE31BB081]] INFO  org.apache.solr.SolrTestCaseJ4  ? ###Ending testStatistics
[junit4:junit4]   1> 375150 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.CoreContainer  ? Shutting down CoreContainer instance=1985493745
[junit4:junit4]   1> 375151 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCore  ? [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@7b9e5560
[junit4:junit4]   1> 375216 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.update.UpdateHandler  ? closing DirectUpdateHandler2{commits=59,autocommit maxTime=15000ms,autocommits=1,soft autocommits=3,optimizes=2,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=140,cumulative_deletesById=5,cumulative_deletesByQuery=27,cumulative_errors=1}
[junit4:junit4]   1> 375219 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.update.SolrCoreState  ? Closing SolrCoreState
[junit4:junit4]   1> 375225 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.update.DefaultSolrCoreState  ? SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   1> 375227 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.update.DefaultSolrCoreState  ? closing IndexWriter with IndexWriterCloser
[junit4:junit4]   1> 375238 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.SolrCore  ? [collection1] Closing main searcher on request.
[junit4:junit4]   1> 375240 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> 375242 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956 [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956;done=false>>]
[junit4:junit4]   1> 375243 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956
[junit4:junit4]   1> 375244 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? looking to close /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956/index;done=false>>]
[junit4:junit4]   1> 375245 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.core.CachingDirectoryFactory  ? Closing directory: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleXMLTest-1368564418956/index
[junit4:junit4]   1> 375246 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.eclipse.jetty.server.handler.ContextHandler  ? stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   1> 375316 [SUITE-SolrExampleXMLTest-seed#[AF041A5DE31BB081]-worker] INFO  org.apache.solr.SolrTestCaseJ4  ? ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=417), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=417)), sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=en_IN, timezone=America/Costa_Rica
[junit4:junit4]   2> NOTE: Mac OS X 10.8.3 x86_64/Oracle Corporation 1.7.0_21 (64-bit)/cpus=2,threads=1,free=16786936,total=53010432
[junit4:junit4]   2> NOTE: All tests run in this JVM: [MergeIndexesEmbeddedTest, LargeVolumeEmbeddedTest, ClientUtilsTest, CloudSolrServerTest, LargeVolumeJettyTest, TestCoreAdmin, SolrExampleStreamingTest, DocumentAnalysisResponseTest, TestDocumentObjectBinder, FacetFieldTest, TestSpellCheckResponse, TestNamedListCodec, SolrExampleXMLTest]
[junit4:junit4] Completed in 214.62s, 26 tests, 1 error <<< FAILURES!

[...truncated 99 lines...]
BUILD FAILED
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/build.xml:383: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/build.xml:363: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/build.xml:39: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build.xml:194: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/common-build.xml:437: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:1243: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:887: There were test failures: 45 suites, 268 tests, 1 error

Total time: 127 minutes 54 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message