You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/06/27 15:44:48 UTC
[JENKINS] Lucene-Solr-4.x-MacOSX (64bit/jdk1.6.0) - Build # 585 -
Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-MacOSX/585/
Java: 64bit/jdk1.6.0 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.embedded.SolrExampleStreamingTest.testWaitOptions
Error Message:
IOException occured when talking to server at: https://127.0.0.1:55480/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:55480/solr/collection1
at __randomizedtesting.SeedInfo.seed([1372F9863CFC47AC:5533743D1FFA094F]: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.impl.ConcurrentUpdateSolrServer.request(ConcurrentUpdateSolrServer.java:265)
at org.apache.solr.client.solrj.embedded.SolrExampleStreamingTest.testWaitOptions(SolrExampleStreamingTest.java:99)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
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:680)
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)
... 43 more
Build Log:
[...truncated 9905 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleStreamingTest
[junit4:junit4] 1> 0 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.SolrJettyTestBase � Randomized ssl (true) and clientAuth (false)
[junit4:junit4] 1> 21 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.SolrTestCaseJ4 � ####initCore
[junit4:junit4] 1> 24 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.SolrTestCaseJ4 � ####initCore end
[junit4:junit4] 1> 965 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.eclipse.jetty.server.Server � jetty-8.1.10.v20130312
[junit4:junit4] 1> 2111 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.eclipse.jetty.util.ssl.SslContextFactory � Enabled Protocols [SSLv2Hello, SSLv3, TLSv1] of [SSLv2Hello, SSLv3, TLSv1]
[junit4:junit4] 1> 2269 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.eclipse.jetty.server.AbstractConnector � Started SslSelectChannelConnector@127.0.0.1:55480
[junit4:junit4] 1> 2400 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter � SolrDispatchFilter.init()
[junit4:junit4] 1> 2586 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.core.SolrResourceLoader � JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 1> 2589 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 2638 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 2683 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.core.CoreContainer � New CoreContainer 2125082354
[junit4:junit4] 1> 2685 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 2686 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3853 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3855 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3860 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3870 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3872 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3876 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3878 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3880 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3882 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3896 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3901 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3904 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3912 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3914 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3916 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3918 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3921 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3922 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3924 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 3978 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory � Setting socketTimeout to: 0
[junit4:junit4] 1> 3980 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory � Setting urlScheme to: http://
[junit4:junit4] 1> 3980 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory � Setting connTimeout to: 0
[junit4:junit4] 1> 3981 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory � Setting maxConnectionsPerHost to: 20
[junit4:junit4] 1> 3982 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory � Setting corePoolSize to: 0
[junit4:junit4] 1> 3983 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory � Setting maximumPoolSize to: 2147483647
[junit4:junit4] 1> 3984 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory � Setting maxThreadIdleTime to: 5
[junit4:junit4] 1> 3985 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory � Setting sizeOfQueue to: -1
[junit4:junit4] 1> 3986 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory � Setting fairnessPolicy to: false
[junit4:junit4] 1> 4055 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 4667 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.logging.LogWatcher � Registering Log Listener
[junit4:junit4] 1> 4881 [coreLoadExecutor-3-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> 4890 [coreLoadExecutor-3-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> 5221 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrConfig � Adding specified lib dirs to ClassLoader
[junit4:junit4] 1> 5228 [coreLoadExecutor-3-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> 5230 [coreLoadExecutor-3-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> 5231 [coreLoadExecutor-3-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> 5232 [coreLoadExecutor-3-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> 5238 [coreLoadExecutor-3-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> 5238 [coreLoadExecutor-3-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> 5240 [coreLoadExecutor-3-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> 5241 [coreLoadExecutor-3-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> 5651 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrConfig � Using Lucene MatchVersion: LUCENE_43
[junit4:junit4] 1> 6072 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.Config � Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 1> 6133 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.schema.IndexSchema � Reading Solr Schema from schema.xml
[junit4:junit4] 1> 6309 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.schema.IndexSchema � [collection1] Schema name=example
[junit4:junit4] 1> 9636 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.schema.IndexSchema � unique key field: id
[junit4:junit4] 1> 11836 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider � Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 11877 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider � Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 12673 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 12743 [coreLoadExecutor-3-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-SolrExampleStreamingTest-1372340209959/
[junit4:junit4] 1> 12750 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.JmxMonitoredMap � JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@45c3e9ba
[junit4:junit4] 1> 12816 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4] 1> 12817 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4:junit4] 1> 12874 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 13238 [coreLoadExecutor-3-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-SolrExampleStreamingTest-1372340209959
[junit4:junit4] 1> 13239 [coreLoadExecutor-3-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-SolrExampleStreamingTest-1372340209959/index/
[junit4:junit4] 1> 13240 [coreLoadExecutor-3-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-SolrExampleStreamingTest-1372340209959/index' doesn't exist. Creating new index...
[junit4:junit4] 1> 13263 [coreLoadExecutor-3-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-SolrExampleStreamingTest-1372340209959/index
[junit4:junit4] 1> 13938 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_1,generation=1}
[junit4:junit4] 1> 13944 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � newest commit generation = 1
[junit4:junit4] 1> 13997 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � created json: solr.JSONResponseWriter
[junit4:junit4] 1> 13998 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4] 1> 14003 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � created velocity: solr.VelocityResponseWriter
[junit4:junit4] 1> 14048 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � created xslt: solr.XSLTResponseWriter
[junit4:junit4] 1> 14049 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.response.XSLTResponseWriter � xsltCacheLifetimeSeconds=5
[junit4:junit4] 1> 17613 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /select: solr.SearchHandler
[junit4:junit4] 1> 17617 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /query: solr.SearchHandler
[junit4:junit4] 1> 17652 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /get: solr.RealTimeGetHandler
[junit4:junit4] 1> 17657 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /browse: solr.SearchHandler
[junit4:junit4] 1> 17712 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /update: solr.UpdateRequestHandler
[junit4:junit4] 1> 17744 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4] 1> 17777 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /update/csv: solr.CSVRequestHandler
[junit4:junit4] 1> 17777 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 17784 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 17785 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 17786 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 17787 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 17788 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 17823 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /admin/: solr.admin.AdminHandlers
[junit4:junit4] 1> 17849 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /admin/ping: solr.PingRequestHandler
[junit4:junit4] 1> 17880 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 1> 17990 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /replication: solr.ReplicationHandler
[junit4:junit4] 1> 17990 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 17992 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /spell: solr.SearchHandler
[junit4:junit4] 1> 17992 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 17993 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /tvrh: solr.SearchHandler
[junit4:junit4] 1> 18002 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 18004 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /terms: solr.SearchHandler
[junit4:junit4] 1> 18005 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 18006 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers � created /elevate: solr.SearchHandler
[junit4:junit4] 1> 18051 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.loader.XMLLoader � xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 18099 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.loader.XMLLoader � xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 18103 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.loader.XMLLoader � xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 18197 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@28bd36fa main
[junit4:junit4] 1> 18852 [coreLoadExecutor-3-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-SolrExampleStreamingTest-1372340209959/tlog
[junit4:junit4] 1> 19207 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � Hard AutoCommit: if uncommited for 15000ms;
[junit4:junit4] 1> 19208 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore � Soft AutoCommit: disabled
[junit4:junit4] 1> 19211 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent � Initializing spell checkers
[junit4:junit4] 1> 19323 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.spelling.DirectSolrSpellChecker � init: {name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4:junit4] 1> 19421 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent � No queryConverter defined, using default converter
[junit4:junit4] 1> 19451 [coreLoadExecutor-3-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> 19896 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.ReplicationHandler � Commits will be reserved for 10000
[junit4:junit4] 1> 19898 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@28bd36fa main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 20530 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] webapp=null path=null params={event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false} hits=0 status=0 QTime=595
[junit4:junit4] 1> 20536 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 20536 [searcherExecutor-4-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent � Loading spell index for spellchecker: default
[junit4:junit4] 1> 20538 [searcherExecutor-4-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent � Loading spell index for spellchecker: wordbreak
[junit4:junit4] 1> 20547 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@28bd36fa main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 20573 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.CoreContainer � registering core: collection1
[junit4:junit4] 1> 21033 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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> 21034 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter � SolrDispatchFilter.init() done
[junit4:junit4] 1> 21035 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.SolrJettyTestBase � Jetty Assigned Port#55480
[junit4:junit4] 2> Creating dataDir: /Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/./solrtest-SolrExampleStreamingTest-1372340209959
[junit4:junit4] 1> 21107 [TEST-SolrExampleStreamingTest.testStatistics-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testStatistics
[junit4:junit4] 1> 21201 [TEST-SolrExampleStreamingTest.testStatistics-seed#[1372F9863CFC47AC]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil � Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 23878 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_1,generation=1}
[junit4:junit4] 1> 23895 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � newest commit generation = 1
[junit4:junit4] 1> 23991 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@246af18d realtime
[junit4:junit4] 1> 24004 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003032916328448)} 0 337
[junit4:junit4] 1> 24237 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 24239 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � No uncommitted changes. Skipping IW.commit.
[junit4:junit4] 1> 24241 [qtp1903736395-30] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@2688e83d main
[junit4:junit4] 1> 24260 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@2688e83d main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 24261 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 24270 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 24275 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@2688e83d main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 24279 [qtp1903736395-30] 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 42
[junit4:junit4] 1> 24297 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=9
[junit4:junit4] 1> 24323 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6e66da2c
[junit4:junit4] 1> 24335 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@714fdb76
[junit4:junit4] 1> 24343 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@70cfaf6e
[junit4:junit4] 1> 24339 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@2ff44aa6
[junit4:junit4] 1> 24331 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@71bc081f
[junit4:junit4] 1> 24422 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@71bc081f
[junit4:junit4] 1> 24774 [qtp1903736395-28] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc4 (1439003033646137344)]} 0 291
[junit4:junit4] 1> 24775 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc2 (1439003033653477376)]} 0 286
[junit4:junit4] 1> 24774 [qtp1903736395-30] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc1 (1439003033639845888)]} 0 297
[junit4:junit4] 1> 24783 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: doc2 is 200
[junit4:junit4] 1> 24785 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@70cfaf6e
[junit4:junit4] 1> 24793 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: doc1 is 200
[junit4:junit4] 1> 24799 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@714fdb76
[junit4:junit4] 1> 24799 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: doc4 is 200
[junit4:junit4] 1> 24801 [qtp1903736395-26] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc0 (1439003033534988288), doc3 (1439003033952321536), doc5 (1439003033963855872), doc6 (1439003033965953024), doc7 (1439003033967001600), doc8 (1439003033972244480), doc9 (1439003033975390208)]} 0 430
[junit4:junit4] 1> 24805 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: doc0 is 200
[junit4:junit4] 1> 24807 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@2ff44aa6
[junit4:junit4] 1> 24809 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6e66da2c
[junit4:junit4] 1> 24815 [qtp1903736395-29] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 25131 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_1,generation=1}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_2,generation=2}
[junit4:junit4] 1> 25133 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � newest commit generation = 2
[junit4:junit4] 1> 25344 [qtp1903736395-29] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@7f1f91ac main
[junit4:junit4] 1> 25345 [qtp1903736395-29] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 25346 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@7f1f91ac main{StandardDirectoryReader(segments_2:5:nrt _0(4.4):C8 _1(4.4):C2)}
[junit4:junit4] 1> 25346 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 25361 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@7f1f91ac main{StandardDirectoryReader(segments_2:5:nrt _0(4.4):C8 _1(4.4):C2)}
[junit4:junit4] 1> 25363 [qtp1903736395-29] 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 548
[junit4:junit4] 1> 25437 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=10 status=0 QTime=61
[junit4:junit4] 1> 25779 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=xml&version=2.2&rows=0} hits=10 status=0 QTime=299
[junit4:junit4] 1> 26005 [qtp1903736395-26] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@54861a09 realtime
[junit4:junit4] 1> 26007 [qtp1903736395-26] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003035019771904)} 0 214
[junit4:junit4] 1> 26014 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 26015 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_2,generation=2}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_3,generation=3}
[junit4:junit4] 1> 26016 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � newest commit generation = 3
[junit4:junit4] 1> 26022 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@1d667df0 main
[junit4:junit4] 1> 26025 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 26025 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@1d667df0 main{StandardDirectoryReader(segments_2:7:nrt)}
[junit4:junit4] 1> 26027 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 26047 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@1d667df0 main{StandardDirectoryReader(segments_2:7:nrt)}
[junit4:junit4] 1> 26109 [qtp1903736395-32] 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 95
[junit4:junit4] 1> 26125 [qtp1903736395-29] 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> 26129 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@47bb2cb
[junit4:junit4] 1> 26131 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@55172fb9
[junit4:junit4] 1> 26132 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@72423da9
[junit4:junit4] 1> 26136 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@72423da9
[junit4:junit4] 1> 26139 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@333cb1eb
[junit4:junit4] 1> 26140 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@333cb1eb
[junit4:junit4] 1> 26132 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5b7b2712
[junit4:junit4] 1> 26145 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5b7b2712
[junit4:junit4] 1> 26131 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@653e4653
[junit4:junit4] 1> 26154 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@653e4653
[junit4:junit4] 1> 26392 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc10 (1439003035392016384), doc12 (1439003035395162112), doc13 (1439003035397259264), doc14 (1439003035398307840)]} 0 244
[junit4:junit4] 1> 26395 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: doc10 is 200
[junit4:junit4] 1> 26396 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@47bb2cb
[junit4:junit4] 1> 26407 [qtp1903736395-28] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc11 (1439003035403550720)]} 0 249
[junit4:junit4] 1> 26411 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: doc11 is 200
[junit4:junit4] 1> 26412 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@55172fb9
[junit4:junit4] 1> 26418 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 26444 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_3,generation=3}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_4,generation=4}
[junit4:junit4] 1> 26445 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � newest commit generation = 4
[junit4:junit4] 1> 26450 [qtp1903736395-26] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@ada6d09 main
[junit4:junit4] 1> 26454 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 26454 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@ada6d09 main{StandardDirectoryReader(segments_4:11:nrt _2(4.4):C4 _3(4.4):C1)}
[junit4:junit4] 1> 26455 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 26467 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@ada6d09 main{StandardDirectoryReader(segments_4:11:nrt _2(4.4):C4 _3(4.4):C1)}
[junit4:junit4] 1> 26480 [qtp1903736395-26] 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 62
[junit4:junit4] 1> 26488 [qtp1903736395-32] 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> 26527 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=xml&version=2.2&rows=0} hits=5 status=0 QTime=1
[junit4:junit4] 1> 26540 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@42293b53 realtime
[junit4:junit4] 1> 26541 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003035797815296)} 0 6
[junit4:junit4] 1> 26548 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 26550 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_4,generation=4}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_5,generation=5}
[junit4:junit4] 1> 26551 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � newest commit generation = 5
[junit4:junit4] 1> 26554 [qtp1903736395-30] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@1c7b0f4d main
[junit4:junit4] 1> 26559 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 26559 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@1c7b0f4d main{StandardDirectoryReader(segments_4:13:nrt)}
[junit4:junit4] 1> 26561 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 26588 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@1c7b0f4d main{StandardDirectoryReader(segments_4:13:nrt)}
[junit4:junit4] 1> 26607 [qtp1903736395-30] 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 59
[junit4:junit4] 1> 26615 [qtp1903736395-28] 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> 26632 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@36d98810
[junit4:junit4] 1> 26632 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@3033b503
[junit4:junit4] 1> 26632 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@373ee92
[junit4:junit4] 1> 26633 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1e893918
[junit4:junit4] 1> 26633 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@12b0f0ae
[junit4:junit4] 1> 26654 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@12b0f0ae
[junit4:junit4] 1> 26894 [qtp1903736395-26] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc0 (1439003035944615936), doc4 (1439003035977121792), doc5 (1439003035980267520)]} 0 220
[junit4:junit4] 1> 26897 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc1 (1439003035942518784)]} 0 226
[junit4:junit4] 1> 26898 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: doc0 is 200
[junit4:junit4] 1> 26899 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@36d98810
[junit4:junit4] 1> 26901 [qtp1903736395-29] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc3 (1439003035943567360), doc6 (1439003035958247424), doc7 (1439003035960344576), doc8 (1439003035963490304), doc9 (1439003035977121793)]} 0 229
[junit4:junit4] 1> 26899 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: doc1 is 200
[junit4:junit4] 1> 26908 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@3033b503
[junit4:junit4] 1> 26909 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: doc3 is 200
[junit4:junit4] 1> 26910 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1e893918
[junit4:junit4] 1> 26912 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[doc2 (1439003035958247425)]} 0 235
[junit4:junit4] 1> 26917 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: doc2 is 200
[junit4:junit4] 1> 26919 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@373ee92
[junit4:junit4] 1> 26931 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 26956 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_5,generation=5}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_6,generation=6}
[junit4:junit4] 1> 26957 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � newest commit generation = 6
[junit4:junit4] 1> 27016 [qtp1903736395-28] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@77d80e9 main
[junit4:junit4] 1> 27018 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 27018 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@77d80e9 main{StandardDirectoryReader(segments_6:17:nrt _4(4.4):C9 _5(4.4):C1)}
[junit4:junit4] 1> 27020 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 27030 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@77d80e9 main{StandardDirectoryReader(segments_6:17:nrt _4(4.4):C9 _5(4.4):C1)}
[junit4:junit4] 1> 27032 [qtp1903736395-28] 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 101
[junit4:junit4] 1> 27054 [qtp1903736395-30] 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=10
[junit4:junit4] 1> 27066 [qtp1903736395-26] 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=1
[junit4:junit4] 1> 27154 [qtp1903736395-31] 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=75
[junit4:junit4] 1> 27160 [TEST-SolrExampleStreamingTest.testStatistics-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testStatistics
[junit4:junit4] 1> 27239 [TEST-SolrExampleStreamingTest.testSetNullUpdates-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testSetNullUpdates
[junit4:junit4] 1> 27240 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1b845568
[junit4:junit4] 1> 27498 [qtp1903736395-29] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdates (1439003036543352832)]} 0 253
[junit4:junit4] 1> 27505 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: testSetNullUpdates is 200
[junit4:junit4] 1> 27506 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1b845568
[junit4:junit4] 1> 27512 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 27523 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_6,generation=6}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_7,generation=7}
[junit4:junit4] 1> 27524 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � newest commit generation = 7
[junit4:junit4] 1> 27527 [qtp1903736395-28] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@3a0b2771 main
[junit4:junit4] 1> 27529 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@3a0b2771 main{StandardDirectoryReader(segments_7:19:nrt _4(4.4):C9 _5(4.4):C1 _6(4.4):C1)}
[junit4:junit4] 1> 27530 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 27531 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 27541 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@3a0b2771 main{StandardDirectoryReader(segments_7:19:nrt _4(4.4):C9 _5(4.4):C1 _6(4.4):C1)}
[junit4:junit4] 1> 27543 [qtp1903736395-28] 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> 27653 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@3af4d6b3
[junit4:junit4] 1> 27910 [qtp1903736395-30] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdates (1439003037027794944)]} 0 252
[junit4:junit4] 1> 27912 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: testSetNullUpdates is 200
[junit4:junit4] 1> 27913 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@3af4d6b3
[junit4:junit4] 1> 27917 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 27931 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_7,generation=7}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_8,generation=8}
[junit4:junit4] 1> 27932 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 8
[junit4:junit4] 1> 27936 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@c390508 main
[junit4:junit4] 1> 27940 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 27940 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@c390508 main{StandardDirectoryReader(segments_8:23:nrt _4(4.4):C9 _5(4.4):C1 _7(4.4):C1)}
[junit4:junit4] 1> 27942 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 27950 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@c390508 main{StandardDirectoryReader(segments_8:23:nrt _4(4.4):C9 _5(4.4):C1 _7(4.4):C1)}
[junit4:junit4] 1> 27955 [qtp1903736395-31] 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> 27964 [qtp1903736395-32] 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=2
[junit4:junit4] 1> 27973 [TEST-SolrExampleStreamingTest.testSetNullUpdates-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testSetNullUpdates
[junit4:junit4] 1> 28002 [TEST-SolrExampleStreamingTest.testLukeHandler-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testLukeHandler
[junit4:junit4] 1> 28011 [qtp1903736395-29] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@54563cc2 realtime
[junit4:junit4] 1> 28012 [qtp1903736395-29] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003037341319168)} 0 6
[junit4:junit4] 1> 28016 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@69af0fcf
[junit4:junit4] 1> 28016 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5c538b31
[junit4:junit4] 1> 28016 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6437153d
[junit4:junit4] 1> 28025 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@424b29cf
[junit4:junit4] 1> 28026 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@424b29cf
[junit4:junit4] 1> 28027 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6437153d
[junit4:junit4] 1> 28280 [qtp1903736395-28] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID0 (1439003037364387840), ID2 (1439003037385359360)]} 0 252
[junit4:junit4] 1> 28283 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: ID0 is 200
[junit4:junit4] 1> 28290 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@69af0fcf
[junit4:junit4] 1> 28307 [qtp1903736395-26] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[ID1 (1439003037387456512), ID3 (1439003037389553664), ID4 (1439003037389553665)]} 0 256
[junit4:junit4] 1> 28309 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: ID1 is 200
[junit4:junit4] 1> 28310 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@5c538b31
[junit4:junit4] 1> 28315 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 28328 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_8,generation=8}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_9,generation=9}
[junit4:junit4] 1> 28329 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 9
[junit4:junit4] 1> 28336 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@391a74ef main
[junit4:junit4] 1> 28339 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 28340 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@391a74ef main{StandardDirectoryReader(segments_9:29:nrt _9(4.4):C3 _8(4.4):C2)}
[junit4:junit4] 1> 28341 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 28356 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@391a74ef main{StandardDirectoryReader(segments_9:29:nrt _9(4.4):C3 _8(4.4):C2)}
[junit4:junit4] 1> 28366 [qtp1903736395-31] 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 51
[junit4:junit4] 1> 28378 [qtp1903736395-32] 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> 28467 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/admin/luke params={wt=xml&version=2.2} status=0 QTime=70
[junit4:junit4] 1> 28506 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=xml&version=2.2} status=0 QTime=4
[junit4:junit4] 1> 28720 [TEST-SolrExampleStreamingTest.testLukeHandler-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testLukeHandler
[junit4:junit4] 1> 28760 [TEST-SolrExampleStreamingTest.testErrorHandling-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testErrorHandling
[junit4:junit4] 1> 28974 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � Ignoring exception matching ignore_exception
[junit4:junit4] 1> 28975 [qtp1903736395-26] 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=201
[junit4:junit4] 1> 29213 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 227
[junit4:junit4] 1> 29214 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � Ignoring exception matching ignore_exception
[junit4:junit4] 1> 29219 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@27feae0f
[junit4:junit4] 1> 29223 [qtp1903736395-29] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 0
[junit4:junit4] 1> 29225 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � Ignoring exception matching uniqueKey
[junit4:junit4] 1> 29474 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: DOCID is 400
[junit4:junit4] 1> 29476 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@27feae0f
[junit4:junit4] 1> 29479 [TEST-SolrExampleStreamingTest.testErrorHandling-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testErrorHandling
[junit4:junit4] 1> 29514 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testWaitOptions
[junit4:junit4] 1> 29515 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[1372F9863CFC47AC]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil � Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 29524 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[1372F9863CFC47AC]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � blocking for commit/optimize
[junit4:junit4] 1> 29568 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 29579 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_9,generation=9}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_a,generation=10}
[junit4:junit4] 1> 29580 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 10
[junit4:junit4] 1> 29582 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@382f3bf0 main
[junit4:junit4] 1> 29584 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 29584 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@382f3bf0 main{StandardDirectoryReader(segments_a:31:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1)}
[junit4:junit4] 1> 29585 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 29592 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@382f3bf0 main{StandardDirectoryReader(segments_a:31:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1)}
[junit4:junit4] 1> 29595 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[42 (1439003038970806272)],commit=} 0 34
[junit4:junit4] 1> 29609 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[1372F9863CFC47AC]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � blocking for commit/optimize
[junit4:junit4] 1> 29609 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4bcd2d49
[junit4:junit4] 1> 29638 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 29741 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_a,generation=10}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_b,generation=11}
[junit4:junit4] 1> 29746 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � newest commit generation = 11
[junit4:junit4] 1> 29750 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@369133f6 main
[junit4:junit4] 1> 29751 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@369133f6 main{StandardDirectoryReader(segments_b:33:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1 _b(4.4):C1)}
[junit4:junit4] 1> 29752 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 29765 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 29769 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@369133f6 main{StandardDirectoryReader(segments_b:33:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1 _b(4.4):C1)}
[junit4:junit4] 1> 29872 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 29873 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � No uncommitted changes. Skipping IW.commit.
[junit4:junit4] 1> 29874 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@5e1387c6 main
[junit4:junit4] 1> 29875 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 29876 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={waitSearcher=false&commit=true&wt=javabin&version=2&softCommit=false} {add=[43 (1439003039045255168)],commit=,commit=} 0 258
[junit4:junit4] 1> 29878 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@5e1387c6 main{StandardDirectoryReader(segments_b:33:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1 _b(4.4):C1)}
[junit4:junit4] 1> 29879 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 29886 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@5e1387c6 main{StandardDirectoryReader(segments_b:33:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1 _b(4.4):C1)}
[junit4:junit4] 1> 29890 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: 43 is 200
[junit4:junit4] 1> 29891 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4bcd2d49
[junit4:junit4] 1> 29936 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 29947 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_b,generation=11}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_c,generation=12}
[junit4:junit4] 1> 29948 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � newest commit generation = 12
[junit4:junit4] 1> 29951 [qtp1903736395-28] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@2f995c9a main
[junit4:junit4] 1> 29952 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@2f995c9a main{StandardDirectoryReader(segments_c:35:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1 _b(4.4):C1 _c(4.4):C1)}
[junit4:junit4] 1> 29953 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 29953 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 29959 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@2f995c9a main{StandardDirectoryReader(segments_c:35:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1 _b(4.4):C1 _c(4.4):C1)}
[junit4:junit4] 1> 29961 [qtp1903736395-28] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[44 (1439003039322079232)],commit=} 0 65
[junit4:junit4] 1> 29965 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[1372F9863CFC47AC]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � blocking for commit/optimize
[junit4:junit4] 1> 29965 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1d59e6df
[junit4:junit4] 1> 29979 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 29987 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_c,generation=12}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_d,generation=13}
[junit4:junit4] 1> 29993 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � newest commit generation = 13
[junit4:junit4] 1> 29995 [qtp1903736395-30] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@4c53ccba main
[junit4:junit4] 1> 29997 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 29998 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@4c53ccba main{StandardDirectoryReader(segments_d:37:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1 _b(4.4):C1 _c(4.4):C1 _d(4.4):C1)}
[junit4:junit4] 1> 29999 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 30006 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@4c53ccba main{StandardDirectoryReader(segments_d:37:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1 _b(4.4):C1 _c(4.4):C1 _d(4.4):C1)}
[junit4:junit4] 1> 30223 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 30225 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � No uncommitted changes. Skipping IW.commit.
[junit4:junit4] 1> 30229 [qtp1903736395-30] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@24cc17f1 main
[junit4:junit4] 1> 30233 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 30236 [qtp1903736395-30] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={waitSearcher=false&commit=true&wt=javabin&version=2&softCommit=false} {add=[45 (1439003039407013888)],commit=,commit=} 0 260
[junit4:junit4] 1> 30233 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@24cc17f1 main{StandardDirectoryReader(segments_d:37:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1 _b(4.4):C1 _c(4.4):C1 _d(4.4):C1)}
[junit4:junit4] 1> 30241 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 30242 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: 45 is 200
[junit4:junit4] 1> 30244 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1d59e6df
[junit4:junit4] 1> 30256 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@24cc17f1 main{StandardDirectoryReader(segments_d:37:nrt _9(4.4):C3 _8(4.4):C2 _a(4.4):C1 _b(4.4):C1 _c(4.4):C1 _d(4.4):C1)}
[junit4:junit4] 1> 30262 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 30373 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_d,generation=13}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_e,generation=14}
[junit4:junit4] 1> 30374 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 14
[junit4:junit4] 1> 30380 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@161e14f0 main
[junit4:junit4] 1> 30382 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@161e14f0 main{StandardDirectoryReader(segments_e:41:nrt _f(4.4):C10)}
[junit4:junit4] 1> 30382 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 30382 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 30394 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@161e14f0 main{StandardDirectoryReader(segments_e:41:nrt _f(4.4):C10)}
[junit4:junit4] 1> 30401 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {add=[46 (1439003039703760896)],optimize=} 0 142
[junit4:junit4] 1> 30404 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[1372F9863CFC47AC]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � blocking for commit/optimize
[junit4:junit4] 1> 30410 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@9f6c439
[junit4:junit4] 1> 30417 [qtp1903736395-29] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=true,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 30556 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_e,generation=14}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_f,generation=15}
[junit4:junit4] 1> 30557 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � newest commit generation = 15
[junit4:junit4] 1> 30561 [qtp1903736395-29] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@5d1eb50b main
[junit4:junit4] 1> 30563 [qtp1903736395-29] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 30565 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@5d1eb50b main{StandardDirectoryReader(segments_f:45:nrt _h(4.4):C11)}
[junit4:junit4] 1> 30566 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 30573 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@5d1eb50b main{StandardDirectoryReader(segments_f:45:nrt _h(4.4):C11)}
[junit4:junit4] 1> 30665 [qtp1903736395-29] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=true,openSearcher=true,waitSearcher=false,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 30674 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_f,generation=15}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_g,generation=16}
[junit4:junit4] 1> 30674 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � newest commit generation = 16
[junit4:junit4] 1> 30677 [qtp1903736395-29] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@6a92e96c main
[junit4:junit4] 1> 30678 [qtp1903736395-29] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 30679 [qtp1903736395-29] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=false&maxSegments=1&wt=javabin&version=2} {add=[47 (1439003039866290176)],optimize=,optimize=} 0 264
[junit4:junit4] 1> 30681 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@6a92e96c main{StandardDirectoryReader(segments_g:47:nrt _i(4.4):C11)}
[junit4:junit4] 1> 30685 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 30687 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: 47 is 200
[junit4:junit4] 1> 30688 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@9f6c439
[junit4:junit4] 1> 30694 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@6a92e96c main{StandardDirectoryReader(segments_g:47:nrt _i(4.4):C11)}
[junit4:junit4] 1> 231071 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 231085 [TEST-SolrExampleStreamingTest.testWaitOptions-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testWaitOptions
[junit4:junit4] 1> 231105 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_g,generation=16}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_h,generation=17}
[junit4:junit4] 1> 231106 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � newest commit generation = 17
[junit4:junit4] 1> 231109 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@4f14e777 main
[junit4:junit4] 1> 231110 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 231114 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@4f14e777 main{StandardDirectoryReader(segments_h:51:nrt _k(4.4):C12)}
[junit4:junit4] 1> 231115 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 231121 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@4f14e777 main{StandardDirectoryReader(segments_h:51:nrt _k(4.4):C12)}
[junit4:junit4] 1> 231147 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {add=[48 (1439003250263064576)],optimize=} 0 82
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleStreamingTest -Dtests.method=testWaitOptions -Dtests.seed=1372F9863CFC47AC -Dtests.slow=true -Dtests.locale=ar_OM -Dtests.timezone=Etc/GMT0 -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR 202s | SolrExampleStreamingTest.testWaitOptions <<<
[junit4:junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:55480/solr/collection1
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([1372F9863CFC47AC:5533743D1FFA094F]: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.impl.ConcurrentUpdateSolrServer.request(ConcurrentUpdateSolrServer.java:265)
[junit4:junit4] > at org.apache.solr.client.solrj.embedded.SolrExampleStreamingTest.testWaitOptions(SolrExampleStreamingTest.java:99)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:680)
[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] > ... 43 more
[junit4:junit4] 1> 231338 [TEST-SolrExampleStreamingTest.testChineseDefaults-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testChineseDefaults
[junit4:junit4] 1> 231409 [qtp1903736395-26] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@3acff49f realtime
[junit4:junit4] 1> 231410 [qtp1903736395-26] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003250620628992)} 0 4
[junit4:junit4] 1> 231421 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 231422 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_h,generation=17}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_i,generation=18}
[junit4:junit4] 1> 231423 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 18
[junit4:junit4] 1> 231425 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@e1641c0 main
[junit4:junit4] 1> 231427 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 231428 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@e1641c0 main{StandardDirectoryReader(segments_h:53:nrt)}
[junit4:junit4] 1> 231505 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 231511 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@e1641c0 main{StandardDirectoryReader(segments_h:53:nrt)}
[junit4:junit4] 1> 231520 [qtp1903736395-31] 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 100
[junit4:junit4] 1> 231528 [qtp1903736395-32] 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> 231531 [TEST-SolrExampleStreamingTest.testChineseDefaults-seed#[1372F9863CFC47AC]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � blocking for commit/optimize
[junit4:junit4] 1> 231538 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 231557 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_i,generation=18}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_j,generation=19}
[junit4:junit4] 1> 231558 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � newest commit generation = 19
[junit4:junit4] 1> 231561 [qtp1903736395-28] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@40442b95 main
[junit4:junit4] 1> 231562 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 231563 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@40442b95 main{StandardDirectoryReader(segments_j:55:nrt _l(4.4):C1)}
[junit4:junit4] 1> 231563 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 231568 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@40442b95 main{StandardDirectoryReader(segments_j:55:nrt _l(4.4):C1)}
[junit4:junit4] 1> 231570 [qtp1903736395-28] 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 (1439003250757992448)],commit=} 0 34
[junit4:junit4] 1> 231600 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={q=????&wt=xml&version=2.2} hits=1 status=0 QTime=24
[junit4:junit4] 1> 231608 [TEST-SolrExampleStreamingTest.testChineseDefaults-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testChineseDefaults
[junit4:junit4] 1> 231636 [TEST-SolrExampleStreamingTest.testGetEmptyResults-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testGetEmptyResults
[junit4:junit4] 1> 231645 [qtp1903736395-26] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@79ec3264 realtime
[junit4:junit4] 1> 231646 [qtp1903736395-26] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003250868092928)} 0 4
[junit4:junit4] 1> 231660 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 231661 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_j,generation=19}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_k,generation=20}
[junit4:junit4] 1> 231662 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 20
[junit4:junit4] 1> 231664 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@13bc6ed3 main
[junit4:junit4] 1> 231666 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@13bc6ed3 main{StandardDirectoryReader(segments_j:57:nrt)}
[junit4:junit4] 1> 231666 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 231666 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 231673 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@13bc6ed3 main{StandardDirectoryReader(segments_j:57:nrt)}
[junit4:junit4] 1> 231677 [qtp1903736395-31] 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> 231681 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@645ad7b2
[junit4:junit4] 1> 231681 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@8071a97
[junit4:junit4] 1> 231950 [qtp1903736395-30] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1439003250928910336)]} 0 250
[junit4:junit4] 1> 231953 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: id1 is 200
[junit4:junit4] 1> 231954 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@645ad7b2
[junit4:junit4] 1> 231979 [qtp1903736395-29] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id2 (1439003250960367616)]} 0 249
[junit4:junit4] 1> 231984 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: id2 is 200
[junit4:junit4] 1> 231984 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@8071a97
[junit4:junit4] 1> 231991 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 231997 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_k,generation=20}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_l,generation=21}
[junit4:junit4] 1> 231998 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 21
[junit4:junit4] 1> 232001 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@46b8705b main
[junit4:junit4] 1> 232003 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@46b8705b main{StandardDirectoryReader(segments_l:59:nrt _m(4.4):C2)}
[junit4:junit4] 1> 232012 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 232006 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 232019 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@46b8705b main{StandardDirectoryReader(segments_l:59:nrt _m(4.4):C2)}
[junit4:junit4] 1> 232021 [qtp1903736395-31] 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> 232056 [qtp1903736395-32] 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=27
[junit4:junit4] 1> 232059 [TEST-SolrExampleStreamingTest.testGetEmptyResults-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testGetEmptyResults
[junit4:junit4] 1> 232086 [TEST-SolrExampleStreamingTest.testPivotFacetsMissing-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testPivotFacetsMissing
[junit4:junit4] 1> 232094 [qtp1903736395-28] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@6e677ea2 realtime
[junit4:junit4] 1> 232096 [qtp1903736395-28] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003251338903552)} 0 5
[junit4:junit4] 1> 232103 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 232105 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_l,generation=21}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_m,generation=22}
[junit4:junit4] 1> 232107 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � newest commit generation = 22
[junit4:junit4] 1> 232113 [qtp1903736395-26] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@66176ccc main
[junit4:junit4] 1> 232116 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@66176ccc main{StandardDirectoryReader(segments_l:61:nrt)}
[junit4:junit4] 1> 232117 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 232118 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 232126 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@66176ccc main{StandardDirectoryReader(segments_l:61:nrt)}
[junit4:junit4] 1> 232130 [qtp1903736395-26] 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> 232138 [qtp1903736395-30] 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> 232142 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@ee67c12
[junit4:junit4] 1> 232398 [qtp1903736395-29] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1439003251397623808), 2 (1439003251400769536), 3 (1439003251402866688), 4 (1439003251403915264), 5 (1439003251406012416), 6 (1439003251407060992), 7 (1439003251409158144), 8 (1439003251411255296), 9 (1439003251412303872), 10 (1439003251414401024), ... (12 adds)]} 0 251
[junit4:junit4] 1> 232400 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: 1 is 200
[junit4:junit4] 1> 232401 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@ee67c12
[junit4:junit4] 1> 232406 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 232419 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_m,generation=22}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_n,generation=23}
[junit4:junit4] 1> 232420 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � newest commit generation = 23
[junit4:junit4] 1> 232442 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@32b11287 main
[junit4:junit4] 1> 232448 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@32b11287 main{StandardDirectoryReader(segments_n:63:nrt _n(4.4):C12)}
[junit4:junit4] 1> 232448 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 232458 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 232462 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@32b11287 main{StandardDirectoryReader(segments_n:63:nrt _n(4.4):C12)}
[junit4:junit4] 1> 232463 [qtp1903736395-32] 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 58
[junit4:junit4] 1> 232901 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=9,phase1=9,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4] 1> 232987 [qtp1903736395-28] 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> 233002 [qtp1903736395-28] 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=524
[junit4:junit4] 1> 233076 [qtp1903736395-30] 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=59
[junit4:junit4] 1> 233081 [TEST-SolrExampleStreamingTest.testPivotFacetsMissing-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testPivotFacetsMissing
[junit4:junit4] 1> 233111 [TEST-SolrExampleStreamingTest.testUpdateRequestWithParameters-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testUpdateRequestWithParameters
[junit4:junit4] 1> 233112 [TEST-SolrExampleStreamingTest.testUpdateRequestWithParameters-seed#[1372F9863CFC47AC]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil � Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 233198 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@26f04d94 realtime
[junit4:junit4] 1> 233199 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003252497580032)} 0 3
[junit4:junit4] 1> 233217 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 233219 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_n,generation=23}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_o,generation=24}
[junit4:junit4] 1> 233220 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � newest commit generation = 24
[junit4:junit4] 1> 233222 [qtp1903736395-26] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@2382815a main
[junit4:junit4] 1> 233225 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 233226 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@2382815a main{StandardDirectoryReader(segments_n:65:nrt)}
[junit4:junit4] 1> 233233 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 233260 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@2382815a main{StandardDirectoryReader(segments_n:65:nrt)}
[junit4:junit4] 1> 233264 [qtp1903736395-26] 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 47
[junit4:junit4] 1> 233270 [concurrentUpdateScheduler-3-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@77aaf64d
[junit4:junit4] 1> 233274 [concurrentUpdateScheduler-3-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@40671416
[junit4:junit4] 1> 233271 [concurrentUpdateScheduler-3-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7910769b
[junit4:junit4] 1> 233278 [concurrentUpdateScheduler-3-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4f9bbd86
[junit4:junit4] 1> 233297 [concurrentUpdateScheduler-3-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4f9bbd86
[junit4:junit4] 1> 233278 [concurrentUpdateScheduler-3-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@684be8b8
[junit4:junit4] 1> 233302 [concurrentUpdateScheduler-3-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@684be8b8
[junit4:junit4] 1> 233293 [concurrentUpdateScheduler-3-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7910769b
[junit4:junit4] 1> 233550 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={overwrite=false&wt=xml&version=2.2} {add=[id1 (1439003252607680512)]} 0 250
[junit4:junit4] 1> 233553 [concurrentUpdateScheduler-3-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: id1 is 200
[junit4:junit4] 1> 233554 [concurrentUpdateScheduler-3-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@77aaf64d
[junit4:junit4] 1> 233559 [qtp1903736395-29] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={overwrite=false&wt=xml&version=2.2} {add=[id1 (1439003252618166272)]} 0 248
[junit4:junit4] 1> 233563 [concurrentUpdateScheduler-3-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: id1 is 200
[junit4:junit4] 1> 233564 [concurrentUpdateScheduler-3-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@40671416
[junit4:junit4] 1> 233570 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 233579 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_o,generation=24}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_p,generation=25}
[junit4:junit4] 1> 233581 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � newest commit generation = 25
[junit4:junit4] 1> 233584 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@616affac main
[junit4:junit4] 1> 233586 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@616affac main{StandardDirectoryReader(segments_p:67:nrt _o(4.4):C2)}
[junit4:junit4] 1> 233587 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 233587 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 233594 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@616affac main{StandardDirectoryReader(segments_p:67:nrt _o(4.4):C2)}
[junit4:junit4] 1> 233595 [qtp1903736395-32] 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> 233602 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=2 status=0 QTime=1
[junit4:junit4] 1> 233606 [TEST-SolrExampleStreamingTest.testUpdateRequestWithParameters-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testUpdateRequestWithParameters
[junit4:junit4] 1> 233641 [TEST-SolrExampleStreamingTest.testAddRetrieve-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testAddRetrieve
[junit4:junit4] 1> 233653 [qtp1903736395-30] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@2d8b62e0 realtime
[junit4:junit4] 1> 233654 [qtp1903736395-30] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003252973633536)} 0 4
[junit4:junit4] 1> 233657 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1a08777c
[junit4:junit4] 1> 233916 [qtp1903736395-28] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1439003252986216448), id2 (1439003252991459328)]} 0 254
[junit4:junit4] 1> 233920 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: id1 is 200
[junit4:junit4] 1> 233922 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1a08777c
[junit4:junit4] 1> 233926 [qtp1903736395-29] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 233937 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_p,generation=25}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_q,generation=26}
[junit4:junit4] 1> 233940 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � newest commit generation = 26
[junit4:junit4] 1> 233945 [qtp1903736395-29] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@20030380 main
[junit4:junit4] 1> 233948 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@20030380 main{StandardDirectoryReader(segments_q:71:nrt _p(4.4):C2)}
[junit4:junit4] 1> 233949 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 233970 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@20030380 main{StandardDirectoryReader(segments_q:71:nrt _p(4.4):C2)}
[junit4:junit4] 1> 233978 [qtp1903736395-29] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 233985 [qtp1903736395-29] 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 59
[junit4:junit4] 1> 234238 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=xml&version=2.2} hits=2 status=0 QTime=235
[junit4:junit4] 1> 234273 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7d557ee8
[junit4:junit4] 1> 234540 [qtp1903736395-26] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[id1 (1439003253641576448), id2 (1439003253644722176)]} 0 254
[junit4:junit4] 1> 234542 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: id1 is 200
[junit4:junit4] 1> 234543 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7d557ee8
[junit4:junit4] 1> 234547 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 234557 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_q,generation=26}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_r,generation=27}
[junit4:junit4] 1> 234558 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 27
[junit4:junit4] 1> 234562 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@75eee7b7 main
[junit4:junit4] 1> 234566 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 234568 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@75eee7b7 main{StandardDirectoryReader(segments_r:75:nrt _q(4.4):C2)}
[junit4:junit4] 1> 234575 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 234583 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@75eee7b7 main{StandardDirectoryReader(segments_r:75:nrt _q(4.4):C2)}
[junit4:junit4] 1> 234585 [qtp1903736395-31] 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> 234593 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=xml&version=2.2} hits=2 status=0 QTime=1
[junit4:junit4] 1> 234608 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={sort=price+asc&q=name:h?llo&wt=xml&version=2.2} hits=1 status=0 QTime=2
[junit4:junit4] 1> 234614 [TEST-SolrExampleStreamingTest.testAddRetrieve-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testAddRetrieve
[junit4:junit4] 1> 234637 [TEST-SolrExampleStreamingTest.testUpdateField-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testUpdateField
[junit4:junit4] 1> 234669 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@7ba614a realtime
[junit4:junit4] 1> 234675 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003254035841024)} 0 12
[junit4:junit4] 1> 234686 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 234687 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_r,generation=27}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_s,generation=28}
[junit4:junit4] 1> 234688 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � newest commit generation = 28
[junit4:junit4] 1> 234691 [qtp1903736395-30] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@1200d083 main
[junit4:junit4] 1> 234695 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@1200d083 main{StandardDirectoryReader(segments_r:77:nrt)}
[junit4:junit4] 1> 234696 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 234703 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@1200d083 main{StandardDirectoryReader(segments_r:77:nrt)}
[junit4:junit4] 1> 234696 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 234706 [qtp1903736395-30] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 20
[junit4:junit4] 1> 234709 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@a0834e7
[junit4:junit4] 1> 234965 [qtp1903736395-26] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1439003254089318400)]} 0 252
[junit4:junit4] 1> 234967 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: unique is 200
[junit4:junit4] 1> 234968 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@a0834e7
[junit4:junit4] 1> 234978 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 234988 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_s,generation=28}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_t,generation=29}
[junit4:junit4] 1> 234989 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � newest commit generation = 29
[junit4:junit4] 1> 234992 [qtp1903736395-28] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@32728d main
[junit4:junit4] 1> 234994 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@32728d main{StandardDirectoryReader(segments_t:79:nrt _r(4.4):C1)}
[junit4:junit4] 1> 234996 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 234996 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 235003 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@32728d main{StandardDirectoryReader(segments_t:79:nrt _r(4.4):C1)}
[junit4:junit4] 1> 235005 [qtp1903736395-28] 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> 235018 [qtp1903736395-29] 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=5
[junit4:junit4] 1> 235021 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@384e9bea
[junit4:junit4] 1> 235025 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {} 0 1
[junit4:junit4] 1> 235275 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: unique is 409
[junit4:junit4] 1> 235276 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@384e9bea
[junit4:junit4] 1> 235284 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 235284 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � No uncommitted changes. Skipping IW.commit.
[junit4:junit4] 1> 235285 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@6a3522b5 main
[junit4:junit4] 1> 235286 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 235287 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@6a3522b5 main{StandardDirectoryReader(segments_t:79:nrt _r(4.4):C1)}
[junit4:junit4] 1> 235288 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 235294 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@6a3522b5 main{StandardDirectoryReader(segments_t:79:nrt _r(4.4):C1)}
[junit4:junit4] 1> 235296 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 12
[junit4:junit4] 1> 235309 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4679cf8c
[junit4:junit4] 1> 235565 [qtp1903736395-26] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1439003254718464000)]} 0 252
[junit4:junit4] 1> 235574 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: unique is 200
[junit4:junit4] 1> 235576 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4679cf8c
[junit4:junit4] 1> 235584 [qtp1903736395-29] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 235594 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_t,generation=29}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_u,generation=30}
[junit4:junit4] 1> 235595 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � newest commit generation = 30
[junit4:junit4] 1> 235599 [qtp1903736395-29] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@47abfd68 main
[junit4:junit4] 1> 235601 [qtp1903736395-29] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 235602 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@47abfd68 main{StandardDirectoryReader(segments_u:83:nrt _s(4.4):C1)}
[junit4:junit4] 1> 235602 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 235609 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@47abfd68 main{StandardDirectoryReader(segments_u:83:nrt _s(4.4):C1)}
[junit4:junit4] 1> 235613 [qtp1903736395-29] 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> 235624 [qtp1903736395-32] 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=2
[junit4:junit4] 1> 235626 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1e933c07
[junit4:junit4] 1> 235885 [qtp1903736395-30] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[unique (1439003255052959744)]} 0 253
[junit4:junit4] 1> 235889 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: unique is 200
[junit4:junit4] 1> 235892 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1e933c07
[junit4:junit4] 1> 235902 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 235922 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_u,generation=30}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_v,generation=31}
[junit4:junit4] 1> 235924 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 31
[junit4:junit4] 1> 235929 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@4d1b92ef main
[junit4:junit4] 1> 235931 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 235935 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@4d1b92ef main{StandardDirectoryReader(segments_v:87:nrt _t(4.4):C1)}
[junit4:junit4] 1> 235936 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 235950 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@4d1b92ef main{StandardDirectoryReader(segments_v:87:nrt _t(4.4):C1)}
[junit4:junit4] 1> 235958 [qtp1903736395-31] 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 56
[junit4:junit4] 1> 235966 [qtp1903736395-26] 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=3
[junit4:junit4] 1> 235972 [TEST-SolrExampleStreamingTest.testUpdateField-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testUpdateField
[junit4:junit4] 1> 236005 [TEST-SolrExampleStreamingTest.testExampleConfig-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testExampleConfig
[junit4:junit4] 1> 236012 [qtp1903736395-29] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@37eaab realtime
[junit4:junit4] 1> 236013 [qtp1903736395-29] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003255447224320)} 0 4
[junit4:junit4] 1> 236026 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@512d297a
[junit4:junit4] 1> 236290 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1112211111 (1439003255469244416)]} 0 260
[junit4:junit4] 1> 236292 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: 1112211111 is 200
[junit4:junit4] 1> 236293 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@512d297a
[junit4:junit4] 1> 236296 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 236302 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_v,generation=31}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_w,generation=32}
[junit4:junit4] 1> 236303 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � newest commit generation = 32
[junit4:junit4] 1> 236358 [qtp1903736395-30] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@69dfe453 main
[junit4:junit4] 1> 236361 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@69dfe453 main{StandardDirectoryReader(segments_w:91:nrt _u(4.4):C1)}
[junit4:junit4] 1> 236361 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 236361 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 236368 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@69dfe453 main{StandardDirectoryReader(segments_w:91:nrt _u(4.4):C1)}
[junit4:junit4] 1> 236370 [qtp1903736395-30] 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 74
[junit4:junit4] 1> 236379 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 236428 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_w,generation=32}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_x,generation=33}
[junit4:junit4] 1> 236429 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 33
[junit4:junit4] 1> 236433 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@456a93b8 main
[junit4:junit4] 1> 236434 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 236435 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@456a93b8 main{StandardDirectoryReader(segments_x:93:nrt _v(4.4):C1)}
[junit4:junit4] 1> 236435 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 236440 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@456a93b8 main{StandardDirectoryReader(segments_x:93:nrt _v(4.4):C1)}
[junit4:junit4] 1> 236441 [qtp1903736395-31] 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 62
[junit4:junit4] 1> 236448 [qtp1903736395-28] 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> 236461 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@3f9261de
[junit4:junit4] 1> 236725 [qtp1903736395-29] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[2 (1439003255931666432), 3 (1439003255935860736), 4 (1439003255941103616), 5 (1439003255943200768)]} 0 254
[junit4:junit4] 1> 236728 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: 2 is 200
[junit4:junit4] 1> 236729 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@3f9261de
[junit4:junit4] 1> 236736 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 236760 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_x,generation=33}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_y,generation=34}
[junit4:junit4] 1> 236761 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � newest commit generation = 34
[junit4:junit4] 1> 236822 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@6b37008a main
[junit4:junit4] 1> 236830 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@6b37008a main{StandardDirectoryReader(segments_y:95:nrt _v(4.4):C1 _w(4.4):C4)}
[junit4:junit4] 1> 236832 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 236842 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 236852 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@6b37008a main{StandardDirectoryReader(segments_y:95:nrt _v(4.4):C1 _w(4.4):C4)}
[junit4:junit4] 1> 236854 [qtp1903736395-32] 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 117
[junit4:junit4] 1> 236862 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 236893 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_y,generation=34}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_z,generation=35}
[junit4:junit4] 1> 236894 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 35
[junit4:junit4] 1> 236907 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@1893c911 main
[junit4:junit4] 1> 236909 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 236910 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@1893c911 main{StandardDirectoryReader(segments_z:97:nrt _x(4.4):C5)}
[junit4:junit4] 1> 236910 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 236925 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@1893c911 main{StandardDirectoryReader(segments_z:97:nrt _x(4.4):C5)}
[junit4:junit4] 1> 236927 [qtp1903736395-31] 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 65
[junit4:junit4] 1> 237086 [qtp1903736395-26] 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=147
[junit4:junit4] 1> 237126 [qtp1903736395-28] 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=4
[junit4:junit4] 1> 237161 [qtp1903736395-29] 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=28
[junit4:junit4] 1> 237165 [TEST-SolrExampleStreamingTest.testExampleConfig-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testExampleConfig
[junit4:junit4] 1> 237187 [TEST-SolrExampleStreamingTest.testAddDelete-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testAddDelete
[junit4:junit4] 1> 237194 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@41616dd6 realtime
[junit4:junit4] 1> 237195 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003256686641152)} 0 4
[junit4:junit4] 1> 237203 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@63721e22
[junit4:junit4] 1> 237460 [qtp1903736395-30] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1439003256704466944)]} 0 252
[junit4:junit4] 1> 237462 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: 0 & 222 is 200
[junit4:junit4] 1> 237470 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@63721e22
[junit4:junit4] 1> 237477 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 237481 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_z,generation=35}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_10,generation=36}
[junit4:junit4] 1> 237481 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � newest commit generation = 36
[junit4:junit4] 1> 237484 [qtp1903736395-26] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@29c2fff0 main
[junit4:junit4] 1> 237492 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@29c2fff0 main{StandardDirectoryReader(segments_10:101:nrt _y(4.4):C1)}
[junit4:junit4] 1> 237493 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 237493 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 237500 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@29c2fff0 main{StandardDirectoryReader(segments_10:101:nrt _y(4.4):C1)}
[junit4:junit4] 1> 237502 [qtp1903736395-26] 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> 237509 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 237519 [qtp1903736395-29] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1439003257029525504)]} 0 2
[junit4:junit4] 1> 237525 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 237526 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � No uncommitted changes. Skipping IW.commit.
[junit4:junit4] 1> 237527 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@770f3801 main
[junit4:junit4] 1> 237528 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@770f3801 main{StandardDirectoryReader(segments_10:103:nrt)}
[junit4:junit4] 1> 237529 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 237557 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@770f3801 main{StandardDirectoryReader(segments_10:103:nrt)}
[junit4:junit4] 1> 237564 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 237565 [qtp1903736395-32] 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> 237627 [qtp1903736395-30] 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> 237666 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@251e9862
[junit4:junit4] 1> 237923 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1439003257188909056)]} 0 253
[junit4:junit4] 1> 237925 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: 0 & 222 is 200
[junit4:junit4] 1> 237926 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@251e9862
[junit4:junit4] 1> 237930 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 237934 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_10,generation=36}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_11,generation=37}
[junit4:junit4] 1> 237935 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � newest commit generation = 37
[junit4:junit4] 1> 237940 [qtp1903736395-28] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@2d6d03ba main
[junit4:junit4] 1> 237941 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@2d6d03ba main{StandardDirectoryReader(segments_11:105:nrt _z(4.4):C1)}
[junit4:junit4] 1> 237942 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 237948 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@2d6d03ba main{StandardDirectoryReader(segments_11:105:nrt _z(4.4):C1)}
[junit4:junit4] 1> 237950 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 237951 [qtp1903736395-28] 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> 237957 [qtp1903736395-29] 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> 237970 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@ba0abea realtime
[junit4:junit4] 1> 237971 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=id:"0\ \&\ 222" (-1439003257500336128)} 0 4
[junit4:junit4] 1> 237977 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 237981 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_11,generation=37}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_12,generation=38}
[junit4:junit4] 1> 237982 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � newest commit generation = 38
[junit4:junit4] 1> 237984 [qtp1903736395-30] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@6326c98 main
[junit4:junit4] 1> 237987 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@6326c98 main{StandardDirectoryReader(segments_11:107:nrt)}
[junit4:junit4] 1> 237988 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 237994 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@6326c98 main{StandardDirectoryReader(segments_11:107:nrt)}
[junit4:junit4] 1> 237988 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 237999 [qtp1903736395-30] 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> 238015 [qtp1903736395-26] 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> 238022 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@620a9239
[junit4:junit4] 1> 238043 [concurrentUpdateScheduler-1-thread-10] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4bc0bec5
[junit4:junit4] 1> 238042 [concurrentUpdateScheduler-1-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@ddb1fe0
[junit4:junit4] 1> 238079 [concurrentUpdateScheduler-1-thread-9] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@ddb1fe0
[junit4:junit4] 1> 238030 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@99ffac2
[junit4:junit4] 1> 238081 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@99ffac2
[junit4:junit4] 1> 238030 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@24060e78
[junit4:junit4] 1> 238082 [concurrentUpdateScheduler-1-thread-7] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@24060e78
[junit4:junit4] 1> 238306 [qtp1903736395-28] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 & 222 (1439003257591562240)]} 0 252
[junit4:junit4] 1> 238353 [concurrentUpdateScheduler-1-thread-10] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: 1 & 222 is 200
[junit4:junit4] 1> 238356 [concurrentUpdateScheduler-1-thread-10] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@4bc0bec5
[junit4:junit4] 1> 238358 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 & 222 (1439003257587367936), 2 & 222 (1439003257623019520)]} 0 308
[junit4:junit4] 1> 238361 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: 0 & 222 is 200
[junit4:junit4] 1> 238362 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@620a9239
[junit4:junit4] 1> 238371 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 238423 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_12,generation=38}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_13,generation=39}
[junit4:junit4] 1> 238424 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � newest commit generation = 39
[junit4:junit4] 1> 238428 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@6cd8f317 main
[junit4:junit4] 1> 238431 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@6cd8f317 main{StandardDirectoryReader(segments_13:111:nrt _10(4.4):C2 _11(4.4):C1)}
[junit4:junit4] 1> 238432 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 238443 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 238445 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@6cd8f317 main{StandardDirectoryReader(segments_13:111:nrt _10(4.4):C2 _11(4.4):C1)}
[junit4:junit4] 1> 238446 [qtp1903736395-32] 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> 238455 [qtp1903736395-30] 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> 238487 [qtp1903736395-26] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {delete=[0 & 222 (-1439003258027769856), 1 & 222 (-1439003258044547072), 2 & 222 (-1439003258045595648)]} 0 18
[junit4:junit4] 1> 238512 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 238513 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � No uncommitted changes. Skipping IW.commit.
[junit4:junit4] 1> 238531 [qtp1903736395-28] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@7c75c2be main
[junit4:junit4] 1> 238534 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 238535 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@7c75c2be main{StandardDirectoryReader(segments_13:113:nrt)}
[junit4:junit4] 1> 238536 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 238546 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@7c75c2be main{StandardDirectoryReader(segments_13:113:nrt)}
[junit4:junit4] 1> 238548 [qtp1903736395-28] 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> 238563 [qtp1903736395-29] 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> 238566 [TEST-SolrExampleStreamingTest.testAddDelete-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testAddDelete
[junit4:junit4] 1> 238595 [TEST-SolrExampleStreamingTest.testPingHandler-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testPingHandler
[junit4:junit4] 1> 238607 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003258169327616)} 0 2
[junit4:junit4] 1> 238622 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 238639 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_13,generation=39}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_14,generation=40}
[junit4:junit4] 1> 238640 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � newest commit generation = 40
[junit4:junit4] 1> 238656 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@6f31a24c main
[junit4:junit4] 1> 238659 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@6f31a24c main{StandardDirectoryReader(segments_13:113:nrt)}
[junit4:junit4] 1> 238660 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 238666 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@6f31a24c main{StandardDirectoryReader(segments_13:113:nrt)}
[junit4:junit4] 1> 238668 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 238669 [qtp1903736395-32] 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 47
[junit4:junit4] 1> 238682 [qtp1903736395-30] 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> 238726 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/admin/ping params={wt=xml&version=2.2} hits=0 status=0 QTime=20
[junit4:junit4] 1> 238727 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/admin/ping params={wt=xml&version=2.2} status=0 QTime=21
[junit4:junit4] 1> 238730 [TEST-SolrExampleStreamingTest.testPingHandler-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testPingHandler
[junit4:junit4] 1> 238806 [TEST-SolrExampleStreamingTest.testMultiContentStreamRequest-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testMultiContentStreamRequest
[junit4:junit4] 1> 238812 [qtp1903736395-28] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003258384285696)} 0 2
[junit4:junit4] 1> 238822 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 238823 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � No uncommitted changes. Skipping IW.commit.
[junit4:junit4] 1> 238824 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@6dcbf6bb main
[junit4:junit4] 1> 238826 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@6dcbf6bb main{StandardDirectoryReader(segments_13:113:nrt)}
[junit4:junit4] 1> 238827 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 238833 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@6dcbf6bb main{StandardDirectoryReader(segments_13:113:nrt)}
[junit4:junit4] 1> 238836 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 238837 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 15
[junit4:junit4] 1> 238844 [qtp1903736395-32] 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> 239204 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 239230 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_14,generation=40}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_15,generation=41}
[junit4:junit4] 1> 239231 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � newest commit generation = 41
[junit4:junit4] 1> 239236 [qtp1903736395-30] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@77feb2ea main
[junit4:junit4] 1> 239237 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@77feb2ea main{StandardDirectoryReader(segments_15:115:nrt _12(4.4):C5)}
[junit4:junit4] 1> 239238 [qtp1903736395-30] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 239238 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 239243 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@77feb2ea main{StandardDirectoryReader(segments_15:115:nrt _12(4.4):C5)}
[junit4:junit4] 1> 239245 [qtp1903736395-30] 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 (1439003258732412928), 6H500F0 (1439003258748141568), TWINX2048-3200PRO (1439003258753384448), VS1GB400C3 (1439003258781696000), VDBDB1A16 (1439003258792181760)],commit=} 0 110
[junit4:junit4] 1> 239251 [qtp1903736395-29] 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> 239259 [TEST-SolrExampleStreamingTest.testMultiContentStreamRequest-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testMultiContentStreamRequest
[junit4:junit4] 1> 239284 [TEST-SolrExampleStreamingTest.testSetNullUpdateOrder-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testSetNullUpdateOrder
[junit4:junit4] 1> 239285 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@43059849
[junit4:junit4] 1> 239542 [qtp1903736395-28] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdateOrder (1439003258887602176)]} 0 253
[junit4:junit4] 1> 239545 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: testSetNullUpdateOrder is 200
[junit4:junit4] 1> 239546 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@43059849
[junit4:junit4] 1> 239550 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 239558 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_15,generation=41}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_16,generation=42}
[junit4:junit4] 1> 239559 [qtp1903736395-31] INFO org.apache.solr.core.SolrCore � newest commit generation = 42
[junit4:junit4] 1> 239564 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@6ddaa877 main
[junit4:junit4] 1> 239568 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@6ddaa877 main{StandardDirectoryReader(segments_16:117:nrt _12(4.4):C5 _13(4.4):C1)}
[junit4:junit4] 1> 239569 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 239575 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@6ddaa877 main{StandardDirectoryReader(segments_16:117:nrt _12(4.4):C5 _13(4.4):C1)}
[junit4:junit4] 1> 239584 [qtp1903736395-31] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 239584 [qtp1903736395-31] 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 35
[junit4:junit4] 1> 239588 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@46f0bf3d
[junit4:junit4] 1> 239846 [qtp1903736395-32] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[testSetNullUpdateOrder (1439003259208466432)]} 0 251
[junit4:junit4] 1> 239849 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: testSetNullUpdateOrder is 200
[junit4:junit4] 1> 239852 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@46f0bf3d
[junit4:junit4] 1> 239856 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 239872 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_16,generation=42}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_17,generation=43}
[junit4:junit4] 1> 239873 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � newest commit generation = 43
[junit4:junit4] 1> 239876 [qtp1903736395-26] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@4674d22e main
[junit4:junit4] 1> 239880 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@4674d22e main{StandardDirectoryReader(segments_17:121:nrt _12(4.4):C5 _14(4.4):C1)}
[junit4:junit4] 1> 239881 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 239881 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 239885 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@4674d22e main{StandardDirectoryReader(segments_17:121:nrt _12(4.4):C5 _14(4.4):C1)}
[junit4:junit4] 1> 239887 [qtp1903736395-26] 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> 239894 [qtp1903736395-29] 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> 239897 [TEST-SolrExampleStreamingTest.testSetNullUpdateOrder-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testSetNullUpdateOrder
[junit4:junit4] 1> 239934 [TEST-SolrExampleStreamingTest.testQueryWithParams-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testQueryWithParams
[junit4:junit4] 1> 239954 [qtp1903736395-28] 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=16
[junit4:junit4] 1> 239958 [TEST-SolrExampleStreamingTest.testQueryWithParams-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testQueryWithParams
[junit4:junit4] 1> 239995 [TEST-SolrExampleStreamingTest.testContentStreamRequest-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testContentStreamRequest
[junit4:junit4] 1> 240006 [qtp1903736395-31] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@5cc7f38c realtime
[junit4:junit4] 1> 240007 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1439003259636285440)} 0 4
[junit4:junit4] 1> 240021 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 240023 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_17,generation=43}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_18,generation=44}
[junit4:junit4] 1> 240023 [qtp1903736395-32] INFO org.apache.solr.core.SolrCore � newest commit generation = 44
[junit4:junit4] 1> 240026 [qtp1903736395-32] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@2cc25ae3 main
[junit4:junit4] 1> 240029 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@2cc25ae3 main{StandardDirectoryReader(segments_17:123:nrt)}
[junit4:junit4] 1> 240029 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 240035 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@2cc25ae3 main{StandardDirectoryReader(segments_17:123:nrt)}
[junit4:junit4] 1> 240036 [qtp1903736395-32] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 240047 [qtp1903736395-32] 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> 240053 [qtp1903736395-30] 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> 240218 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 240233 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_18,generation=44}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_19,generation=45}
[junit4:junit4] 1> 240234 [qtp1903736395-26] INFO org.apache.solr.core.SolrCore � newest commit generation = 45
[junit4:junit4] 1> 240239 [qtp1903736395-26] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@746ac18c main
[junit4:junit4] 1> 240240 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@746ac18c main{StandardDirectoryReader(segments_19:125:nrt _15(4.4):C10)}
[junit4:junit4] 1> 240241 [qtp1903736395-26] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 240241 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 240246 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@746ac18c main{StandardDirectoryReader(segments_19:125:nrt _15(4.4):C10)}
[junit4:junit4] 1> 240248 [qtp1903736395-26] 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 (1439003259803009024), 0553579908 (1439003259807203328), 055357342X (1439003259811397632), 0553293354 (1439003259815591936), 0812521390 (1439003259835514880), 0812550706 (1439003259843903488), 0441385532 (1439003259846000640), 0380014300 (1439003259848097792), 0805080481 (1439003259854389248), 080508049X (1439003259857534976)],commit=} 0 163
[junit4:junit4] 1> 240264 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2} hits=10 status=0 QTime=3
[junit4:junit4] 1> 240293 [TEST-SolrExampleStreamingTest.testContentStreamRequest-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testContentStreamRequest
[junit4:junit4] 1> 240325 [TEST-SolrExampleStreamingTest.testUnicode-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Starting testUnicode
[...truncated too long message...]
[collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 13
[junit4:junit4] 1> 248020 [qtp1903736395-32] 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> 248028 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7561ce13
[junit4:junit4] 1> 248290 [qtp1903736395-31] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[1 (1439003268059496448), 2 (1439003268062642176), 3 (1439003268063690752), 4 (1439003268064739328), 5 (1439003268068933632), 6 (1439003268069982208), 7 (1439003268072079360), 8 (1439003268073127936), 9 (1439003268074176512), 10 (1439003268076273664), ... (12 adds)]} 0 253
[junit4:junit4] 1> 248298 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � Status for: 1 is 200
[junit4:junit4] 1> 248301 [concurrentUpdateScheduler-1-thread-8] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer � finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@7561ce13
[junit4:junit4] 1> 248307 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 248314 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_1r,generation=63}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@4e62974e lockFactory=org.apache.lucene.store.NativeFSLockFactory@53371566),segFN=segments_1s,generation=64}
[junit4:junit4] 1> 248315 [qtp1903736395-28] INFO org.apache.solr.core.SolrCore � newest commit generation = 64
[junit4:junit4] 1> 248318 [qtp1903736395-28] INFO org.apache.solr.search.SolrIndexSearcher � Opening Searcher@7b8aeb1 main
[junit4:junit4] 1> 248320 [qtp1903736395-28] INFO org.apache.solr.update.UpdateHandler � end_commit_flush
[junit4:junit4] 1> 248322 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener sending requests to Searcher@7b8aeb1 main{StandardDirectoryReader(segments_1s:183:nrt _1l(4.4):C12)}
[junit4:junit4] 1> 248323 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � QuerySenderListener done.
[junit4:junit4] 1> 248333 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore � [collection1] Registered new searcher Searcher@7b8aeb1 main{StandardDirectoryReader(segments_1s:183:nrt _1l(4.4):C12)}
[junit4:junit4] 1> 248335 [qtp1903736395-28] INFO org.apache.solr.update.processor.LogUpdateProcessor � [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 28
[junit4:junit4] 1> 248346 [qtp1903736395-30] 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> 248348 [qtp1903736395-30] INFO org.apache.solr.core.SolrCore � UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=1,phase1=1,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4] 1> 248353 [qtp1903736395-30] 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=12
[junit4:junit4] 1> 248367 [qtp1903736395-29] INFO org.apache.solr.core.SolrCore � [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=xml&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2.2&rows=0} hits=11 status=0 QTime=3
[junit4:junit4] 1> 248372 [TEST-SolrExampleStreamingTest.testPivotFacets-seed#[1372F9863CFC47AC]] INFO org.apache.solr.SolrTestCaseJ4 � ###Ending testPivotFacets
[junit4:junit4] 1> 248390 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.core.CoreContainer � Shutting down CoreContainer instance=2125082354
[junit4:junit4] 1> 248390 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.core.SolrCore � [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@51d2bb9f
[junit4:junit4] 1> 248469 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.update.UpdateHandler � closing DirectUpdateHandler2{commits=67,autocommit maxTime=15000ms,autocommits=0,soft autocommits=3,optimizes=6,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=148,cumulative_deletesById=5,cumulative_deletesByQuery=28,cumulative_errors=1}
[junit4:junit4] 1> 248471 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.update.SolrCoreState � Closing SolrCoreState
[junit4:junit4] 1> 248472 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.update.DefaultSolrCoreState � SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 1> 248473 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.update.DefaultSolrCoreState � closing IndexWriter with IndexWriterCloser
[junit4:junit4] 1> 248481 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.core.SolrCore � [collection1] Closing main searcher on request.
[junit4:junit4] 1> 248482 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.core.CachingDirectoryFactory � Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 1> 248484 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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-SolrExampleStreamingTest-1372340209959/index [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleStreamingTest-1372340209959/index;done=false>>]
[junit4:junit4] 1> 248485 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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-SolrExampleStreamingTest-1372340209959/index
[junit4:junit4] 1> 248486 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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-SolrExampleStreamingTest-1372340209959 [CachedDir<<refCount=0;path=/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/solr/build/solr-solrj/test/J0/solrtest-SolrExampleStreamingTest-1372340209959;done=false>>]
[junit4:junit4] 1> 248487 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-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-SolrExampleStreamingTest-1372340209959
[junit4:junit4] 1> 248488 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.eclipse.jetty.server.handler.ContextHandler � stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 1> 248548 [SUITE-SolrExampleStreamingTest-seed#[1372F9863CFC47AC]-worker] INFO org.apache.solr.SolrTestCaseJ4 � ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=ar_OM, timezone=Etc/GMT0
[junit4:junit4] 2> NOTE: Mac OS X 10.8.4 x86_64/Apple Inc. 1.6.0_51 (64-bit)/cpus=2,threads=11,free=13785448,total=73269248
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestDocumentObjectBinder, SolrDocumentTest, AnlysisResponseBaseTest, ClientUtilsTest, SolrExampleStreamingTest]
[junit4:junit4] Completed in 249.38s, 27 tests, 1 error <<< FAILURES!
[...truncated 132 lines...]
BUILD FAILED
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/build.xml:392: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/build.xml:372: 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:1246: The following error occurred while executing this line:
/Users/jenkins/jenkins-slave/workspace/Lucene-Solr-4.x-MacOSX/lucene/common-build.xml:890: There were test failures: 46 suites, 272 tests, 1 error
Total time: 96 minutes 5 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.6.0 -XX:+UseCompressedOops -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure