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/10 18:16:54 UTC
[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.7.0_21) - Build #
2876 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/2876/
Java: 64bit/jdk1.7.0_21 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest.testStatistics
Error Message:
IOException occured when talking to server at: https://127.0.0.1:53798/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:53798/solr/collection1
at __randomizedtesting.SeedInfo.seed([327B3BECBFB7CA61:C5D20888139A1089]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:861)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:601)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:722)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
... 45 more
Build Log:
[...truncated 10629 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest
[junit4:junit4] 1> 167179 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.SolrJettyTestBase – Randomized ssl (true) and clientAuth (false)
[junit4:junit4] 1> 167181 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ####initCore
[junit4:junit4] 1> 167183 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ####initCore end
[junit4:junit4] 1> 167183 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.eclipse.jetty.server.Server – jetty-8.1.10.v20130312
[junit4:junit4] 1> 167197 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.eclipse.jetty.util.ssl.SslContextFactory – Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4:junit4] 1> 167203 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.eclipse.jetty.server.AbstractConnector – Started SslSelectChannelConnector@127.0.0.1:53798
[junit4:junit4] 1> 167203 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init()
[junit4:junit4] 1> 167203 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrResourceLoader – JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 1> 167204 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrResourceLoader – using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr
[junit4:junit4] 1> 167205 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.CoreContainer – looking for solr config file: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\solr.xml
[junit4:junit4] 1> 167205 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.CoreContainer – New CoreContainer 1255881692
[junit4:junit4] 1> 167206 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.CoreContainer – Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\'
[junit4:junit4] 1> 167207 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrResourceLoader – new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\'
[junit4:junit4] 1> 167331 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr
[junit4:junit4] 1> 167332 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\.svn
[junit4:junit4] 1> 167335 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\.svn\prop-base
[junit4:junit4] 1> 167336 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\.svn\props
[junit4:junit4] 1> 167338 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\.svn\text-base
[junit4:junit4] 1> 167340 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\.svn\tmp
[junit4:junit4] 1> 167341 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\.svn\tmp\prop-base
[junit4:junit4] 1> 167342 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\.svn\tmp\props
[junit4:junit4] 1> 167343 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\.svn\tmp\text-base
[junit4:junit4] 1> 167344 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\bin
[junit4:junit4] 1> 167346 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\bin\.svn
[junit4:junit4] 1> 167347 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\bin\.svn\prop-base
[junit4:junit4] 1> 167348 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\bin\.svn\props
[junit4:junit4] 1> 167349 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\bin\.svn\text-base
[junit4:junit4] 1> 167350 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\bin\.svn\tmp
[junit4:junit4] 1> 167351 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\bin\.svn\tmp\prop-base
[junit4:junit4] 1> 167352 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\bin\.svn\tmp\props
[junit4:junit4] 1> 167354 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\bin\.svn\tmp\text-base
[junit4:junit4] 1> 167355 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Discovered properties file C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\core.properties, adding to cores
[junit4:junit4] 1> 167359 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting socketTimeout to: 0
[junit4:junit4] 1> 167360 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting urlScheme to: http://
[junit4:junit4] 1> 167361 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting connTimeout to: 0
[junit4:junit4] 1> 167361 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maxConnectionsPerHost to: 20
[junit4:junit4] 1> 167361 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting corePoolSize to: 0
[junit4:junit4] 1> 167361 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maximumPoolSize to: 2147483647
[junit4:junit4] 1> 167362 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maxThreadIdleTime to: 5
[junit4:junit4] 1> 167362 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting sizeOfQueue to: -1
[junit4:junit4] 1> 167363 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting fairnessPolicy to: false
[junit4:junit4] 1> 167363 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-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> 167370 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.logging.LogWatcher – Registering Log Listener
[junit4:junit4] 1> 167376 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.CoreContainer – Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1
[junit4:junit4] 1> 167376 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrResourceLoader – new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\'
[junit4:junit4] 1> 167409 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrConfig – Adding specified lib dirs to ClassLoader
[junit4:junit4] 1> 167411 [coreLoadExecutor-225-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\extraction\lib).
[junit4:junit4] 1> 167412 [coreLoadExecutor-225-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4:junit4] 1> 167413 [coreLoadExecutor-225-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\clustering\lib).
[junit4:junit4] 1> 167414 [coreLoadExecutor-225-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4:junit4] 1> 167415 [coreLoadExecutor-225-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\langid\lib).
[junit4:junit4] 1> 167416 [coreLoadExecutor-225-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4:junit4] 1> 167417 [coreLoadExecutor-225-thread-1] WARN org.apache.solr.core.SolrResourceLoader – No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\contrib\velocity\lib).
[junit4:junit4] 1> 167417 [coreLoadExecutor-225-thread-1] WARN org.apache.solr.core.SolrResourceLoader – Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\..\..\..\dist).
[junit4:junit4] 1> 167470 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrConfig – Using Lucene MatchVersion: LUCENE_43
[junit4:junit4] 1> 167576 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.Config – Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 1> 167576 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.schema.IndexSchema – Reading Solr Schema from schema.xml
[junit4:junit4] 1> 167583 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.schema.IndexSchema – [collection1] Schema name=example
[junit4:junit4] 1> 168378 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.schema.IndexSchema – unique key field: id
[junit4:junit4] 1> 168400 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider – Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 168407 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider – Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 168440 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 168440 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\
[junit4:junit4] 1> 168440 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.JmxMonitoredMap – JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3be4c6fc
[junit4:junit4] 1> 168440 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4] 1> 168440 [coreLoadExecutor-225-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> 168441 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 168442 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory – return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\
[junit4:junit4] 1> 168442 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\index/
[junit4:junit4] 1> 168442 [coreLoadExecutor-225-thread-1] WARN org.apache.solr.core.SolrCore – [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\index' doesn't exist. Creating new index...
[junit4:junit4] 1> 168443 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory – return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\index
[junit4:junit4] 1> 168446 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1,generation=1}
[junit4:junit4] 1> 168446 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – newest commit generation = 1
[junit4:junit4] 1> 168449 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – created json: solr.JSONResponseWriter
[junit4:junit4] 1> 168449 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4] 1> 168450 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – created velocity: solr.VelocityResponseWriter
[junit4:junit4] 1> 168450 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – created xslt: solr.XSLTResponseWriter
[junit4:junit4] 1> 168450 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.response.XSLTResponseWriter – xsltCacheLifetimeSeconds=5
[junit4:junit4] 1> 168453 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /select: solr.SearchHandler
[junit4:junit4] 1> 168455 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /query: solr.SearchHandler
[junit4:junit4] 1> 168455 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /get: solr.RealTimeGetHandler
[junit4:junit4] 1> 168456 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /browse: solr.SearchHandler
[junit4:junit4] 1> 168457 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update: solr.UpdateRequestHandler
[junit4:junit4] 1> 168458 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4] 1> 168458 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/csv: solr.CSVRequestHandler
[junit4:junit4] 1> 168458 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 168459 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 168460 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 168460 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 168460 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 168460 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 168461 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /admin/: solr.admin.AdminHandlers
[junit4:junit4] 1> 168462 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /admin/ping: solr.PingRequestHandler
[junit4:junit4] 1> 168463 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 1> 168464 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /replication: solr.ReplicationHandler
[junit4:junit4] 1> 168464 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 168464 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /spell: solr.SearchHandler
[junit4:junit4] 1> 168465 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 168465 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /tvrh: solr.SearchHandler
[junit4:junit4] 1> 168465 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 168465 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /terms: solr.SearchHandler
[junit4:junit4] 1> 168465 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 168465 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.RequestHandlers – created /elevate: solr.SearchHandler
[junit4:junit4] 1> 168480 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 168481 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 168484 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 168494 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@39913454 main
[junit4:junit4] 1> 168495 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.update.UpdateHandler – Clearing tlog files, tlogDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\tlog
[junit4:junit4] 1> 168496 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – Hard AutoCommit: if uncommited for 15000ms;
[junit4:junit4] 1> 168496 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.SolrCore – Soft AutoCommit: disabled
[junit4:junit4] 1> 168496 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Initializing spell checkers
[junit4:junit4] 1> 168497 [coreLoadExecutor-225-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> 168497 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – No queryConverter defined, using default converter
[junit4:junit4] 1> 168499 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.handler.component.QueryElevationComponent – Loading QueryElevation from: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\example\solr\collection1\conf\elevate.xml
[junit4:junit4] 1> 168525 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.handler.ReplicationHandler – Commits will be reserved for 10000
[junit4:junit4] 1> 168526 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@39913454 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 168528 [searcherExecutor-226-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=1
[junit4:junit4] 1> 168528 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 168528 [searcherExecutor-226-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Loading spell index for spellchecker: default
[junit4:junit4] 1> 168528 [searcherExecutor-226-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Loading spell index for spellchecker: wordbreak
[junit4:junit4] 1> 168530 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@39913454 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 168533 [coreLoadExecutor-225-thread-1] INFO org.apache.solr.core.CoreContainer – registering core: collection1
[junit4:junit4] 1> 168534 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0
[junit4:junit4] 1> 168534 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init() done
[junit4:junit4] 1> 168535 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.SolrJettyTestBase – Jetty Assigned Port#53798
[junit4:junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\.\solrtest-SolrExampleJettyTest-1370880766141
[junit4:junit4] 1> 168542 [TEST-SolrExampleJettyTest.testExampleConfig-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testExampleConfig
[junit4:junit4] 1> 168542 [TEST-SolrExampleJettyTest.testExampleConfig-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 168612 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1,generation=1}
[junit4:junit4] 1> 168613 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – newest commit generation = 1
[junit4:junit4] 1> 168615 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@36100a64 realtime
[junit4:junit4] 1> 168616 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472671733383168)} 0 8
[junit4:junit4] 1> 168623 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1112211111 (1437472671747014656)]} 0 2
[junit4:junit4] 1> 168628 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 168632 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1,generation=1}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_2,generation=2}
[junit4:junit4] 1> 168633 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – newest commit generation = 2
[junit4:junit4] 1> 168634 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2d17c254 main
[junit4:junit4] 1> 168635 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 168635 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2d17c254 main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C1)}
[junit4:junit4] 1> 168636 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 168638 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2d17c254 main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C1)}
[junit4:junit4] 1> 168639 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 168643 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 168649 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_2,generation=2}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_3,generation=3}
[junit4:junit4] 1> 168651 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – newest commit generation = 3
[junit4:junit4] 1> 168651 [qtp1164189016-466] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7f99a13b main
[junit4:junit4] 1> 168653 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@7f99a13b main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C1)}
[junit4:junit4] 1> 168653 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 168653 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 168654 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@7f99a13b main{StandardDirectoryReader(segments_2:3:nrt _0(4.4):C1)}
[junit4:junit4] 1> 168655 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 13
[junit4:junit4] 1> 168659 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 168669 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1437472671792103424), 3 (1437472671796297728), 4 (1437472671797346304), 5 (1437472671797346305)]} 0 5
[junit4:junit4] 1> 168674 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 168678 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_3,generation=3}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_4,generation=4}
[junit4:junit4] 1> 168679 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – newest commit generation = 4
[junit4:junit4] 1> 168680 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@72cb0646 main
[junit4:junit4] 1> 168681 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 168681 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@72cb0646 main{StandardDirectoryReader(segments_4:5:nrt _0(4.4):C1 _1(4.4):C4)}
[junit4:junit4] 1> 168681 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 168684 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@72cb0646 main{StandardDirectoryReader(segments_4:5:nrt _0(4.4):C1 _1(4.4):C4)}
[junit4:junit4] 1> 168684 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4:junit4] 1> 168689 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 168694 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_4,generation=4}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_5,generation=5}
[junit4:junit4] 1> 168695 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – newest commit generation = 5
[junit4:junit4] 1> 168698 [qtp1164189016-461] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@16401a4 main
[junit4:junit4] 1> 168699 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 168699 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@16401a4 main{StandardDirectoryReader(segments_5:7:nrt _2(4.4):C5)}
[junit4:junit4] 1> 168700 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 168701 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@16401a4 main{StandardDirectoryReader(segments_5:7:nrt _2(4.4):C5)}
[junit4:junit4] 1> 168702 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 13
[junit4:junit4] 1> 168708 [qtp1164189016-459] 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=javabin&version=2} hits=5 status=0 QTime=2
[junit4:junit4] 1> 168714 [qtp1164189016-466] 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=javabin&fq=inStock:true&version=2} hits=2 status=0 QTime=1
[junit4:junit4] 1> 168719 [qtp1164189016-464] 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=javabin&fq={!field+f%3DinStock}true&fq={!term+f%3Dname}hoss&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 168720 [TEST-SolrExampleJettyTest.testExampleConfig-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testExampleConfig
[junit4:junit4] 1> 168733 [TEST-SolrExampleJettyTest.testBadSetup-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testBadSetup
[junit4:junit4] 1> 168733 [TEST-SolrExampleJettyTest.testBadSetup-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testBadSetup
[junit4:junit4] 1> 168745 [TEST-SolrExampleJettyTest.testMultiContentStreamRequest-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testMultiContentStreamRequest
[junit4:junit4] 1> 168750 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3e95b38f realtime
[junit4:junit4] 1> 168750 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472671879135232)} 0 3
[junit4:junit4] 1> 168754 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 168755 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_5,generation=5}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_6,generation=6}
[junit4:junit4] 1> 168755 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – newest commit generation = 6
[junit4:junit4] 1> 168756 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3fc98475 main
[junit4:junit4] 1> 168756 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 168756 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3fc98475 main{StandardDirectoryReader(segments_5:9:nrt)}
[junit4:junit4] 1> 168757 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 168758 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3fc98475 main{StandardDirectoryReader(segments_5:9:nrt)}
[junit4:junit4] 1> 168760 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4:junit4] 1> 168763 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 168789 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 168796 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_6,generation=6}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_7,generation=7}
[junit4:junit4] 1> 168796 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – newest commit generation = 7
[junit4:junit4] 1> 168797 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@29ac3e6f main
[junit4:junit4] 1> 168798 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@29ac3e6f main{StandardDirectoryReader(segments_7:11:nrt _3(4.4):C5)}
[junit4:junit4] 1> 168799 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 168798 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 168800 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@29ac3e6f main{StandardDirectoryReader(segments_7:11:nrt _3(4.4):C5)}
[junit4:junit4] 1> 168801 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&echoParams=ALL&commit=true&a=?&wt=javabin&version=2&softCommit=false} {add=[SP2514N (1437472671908495360), 6H500F0 (1437472671912689664), TWINX2048-3200PRO (1437472671915835392), VS1GB400C3 (1437472671918981120), VDBDB1A16 (1437472671920029696)],commit=} 0 27
[junit4:junit4] 1> 168804 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0
[junit4:junit4] 1> 168805 [TEST-SolrExampleJettyTest.testMultiContentStreamRequest-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testMultiContentStreamRequest
[junit4:junit4] 1> 168812 [TEST-SolrExampleJettyTest.testFaceting-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testFaceting
[junit4:junit4] 1> 168817 [qtp1164189016-464] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@32252252 realtime
[junit4:junit4] 1> 168817 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472671951486976)} 0 1
[junit4:junit4] 1> 168821 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 168822 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_7,generation=7}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_8,generation=8}
[junit4:junit4] 1> 168822 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – newest commit generation = 8
[junit4:junit4] 1> 168822 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@66ad3a47 main
[junit4:junit4] 1> 168823 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 168823 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@66ad3a47 main{StandardDirectoryReader(segments_7:13:nrt)}
[junit4:junit4] 1> 168824 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 168825 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@66ad3a47 main{StandardDirectoryReader(segments_7:13:nrt)}
[junit4:junit4] 1> 168827 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4:junit4] 1> 168830 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 168837 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1437472671969312768), 2 (1437472671970361344), 3 (1437472671971409920), 4 (1437472671972458496), 5 (1437472671972458497), 6 (1437472671972458498), 7 (1437472671973507072), 8 (1437472671973507073), 9 (1437472671973507074), 10 (1437472671973507075)]} 0 4
[junit4:junit4] 1> 168841 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 168842 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_8,generation=8}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_9,generation=9}
[junit4:junit4] 1> 168850 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – newest commit generation = 9
[junit4:junit4] 1> 168852 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4617b0b9 main
[junit4:junit4] 1> 168852 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 168852 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@4617b0b9 main{StandardDirectoryReader(segments_9:15:nrt _4(4.4):C10)}
[junit4:junit4] 1> 168852 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 168854 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@4617b0b9 main{StandardDirectoryReader(segments_9:15:nrt _4(4.4):C10)}
[junit4:junit4] 1> 168854 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4:junit4] 1> 168857 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=0,phase1=0,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4:junit4] 1> 168858 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=1
[junit4:junit4] 1> 168862 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1
[junit4:junit4] 1> 168865 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=4&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1
[junit4:junit4] 1> 168869 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=-1&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1
[junit4:junit4] 1> 168870 [TEST-SolrExampleJettyTest.testFaceting-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testFaceting
[junit4:junit4] 1> 168878 [TEST-SolrExampleJettyTest.testCommitWithinOnDelete-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testCommitWithinOnDelete
[junit4:junit4] 1> 168882 [qtp1164189016-461] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1bcf8906 realtime
[junit4:junit4] 1> 168882 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472672018595840)} 0 2
[junit4:junit4] 1> 168884 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 168885 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_9,generation=9}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_a,generation=10}
[junit4:junit4] 1> 168886 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – newest commit generation = 10
[junit4:junit4] 1> 168886 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1478232f main
[junit4:junit4] 1> 168887 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 168887 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1478232f main{StandardDirectoryReader(segments_9:17:nrt)}
[junit4:junit4] 1> 168888 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 168888 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1478232f main{StandardDirectoryReader(segments_9:17:nrt)}
[junit4:junit4] 1> 168889 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4:junit4] 1> 168893 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 168898 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1437472672036421632)]} 0 3
[junit4:junit4] 1> 168902 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 168904 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_a,generation=10}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_b,generation=11}
[junit4:junit4] 1> 168905 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – newest commit generation = 11
[junit4:junit4] 1> 168907 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4e8699d4 main
[junit4:junit4] 1> 168908 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 168908 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@4e8699d4 main{StandardDirectoryReader(segments_b:19:nrt _5(4.4):C1)}
[junit4:junit4] 1> 168908 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 168909 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@4e8699d4 main{StandardDirectoryReader(segments_b:19:nrt _5(4.4):C1)}
[junit4:junit4] 1> 168910 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 168914 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 168920 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[id3 (-1437472672058441728)]} 0 2
[junit4:junit4] 1> 168924 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 169141 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 169359 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 169580 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 169774 [commitScheduler-229-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4] 1> 169774 [commitScheduler-229-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3baab7c main
[junit4:junit4] 1> 169776 [commitScheduler-229-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 169776 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3baab7c main{StandardDirectoryReader(segments_b:21:nrt)}
[junit4:junit4] 1> 169777 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 169779 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3baab7c main{StandardDirectoryReader(segments_b:21:nrt)}
[junit4:junit4] 1> 169799 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 169800 [TEST-SolrExampleJettyTest.testCommitWithinOnDelete-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testCommitWithinOnDelete
[junit4:junit4] 1> 169809 [TEST-SolrExampleJettyTest.testQueryWithParams-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testQueryWithParams
[junit4:junit4] 1> 169811 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=query&debug=true&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 169813 [TEST-SolrExampleJettyTest.testQueryWithParams-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testQueryWithParams
[junit4:junit4] 1> 169822 [TEST-SolrExampleJettyTest.testErrorHandling-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testErrorHandling
[junit4:junit4] 1> 169825 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – Ignoring exception matching ignore_exception
[junit4:junit4] 1> 169826 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/analysis/field params={analysis.fieldtype=int&wt=javabin&qt=/analysis/field&analysis.fieldvalue=ignore_exception&version=2} status=400 QTime=1
[junit4:junit4] 1> 169831 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 1
[junit4:junit4] 1> 169832 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – Ignoring exception matching ignore_exception
[junit4:junit4] 1> 169836 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 0
[junit4:junit4] 1> 169837 [qtp1164189016-461] ERROR org.apache.solr.core.SolrCore – org.apache.solr.common.SolrException: Document contains multiple values for uniqueKey field: id=[DOCID, DOCID2]
[junit4:junit4] 1> at org.apache.solr.update.AddUpdateCommand.getIndexedId(AddUpdateCommand.java:91)
[junit4:junit4] 1> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:533)
[junit4:junit4] 1> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:399)
[junit4:junit4] 1> at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:100)
[junit4:junit4] 1> at org.apache.solr.handler.loader.XMLLoader.processUpdate(XMLLoader.java:246)
[junit4:junit4] 1> at org.apache.solr.handler.loader.XMLLoader.load(XMLLoader.java:173)
[junit4:junit4] 1> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:92)
[junit4:junit4] 1> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
[junit4:junit4] 1> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
[junit4:junit4] 1> at org.apache.solr.core.SolrCore.execute(SolrCore.java:1894)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:656)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:359)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:155)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4] 1> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4:junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4:junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4] 1> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4:junit4] 1> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4:junit4] 1> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4:junit4] 1> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4] 1> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 1>
[junit4:junit4] 1> 169839 [TEST-SolrExampleJettyTest.testErrorHandling-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testErrorHandling
[junit4:junit4] 1> 169847 [TEST-SolrExampleJettyTest.testPivotFacetsMissing-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPivotFacetsMissing
[junit4:junit4] 1> 169851 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472673036763136)} 0 1
[junit4:junit4] 1> 169855 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 169856 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_b,generation=11}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_c,generation=12}
[junit4:junit4] 1> 169856 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – newest commit generation = 12
[junit4:junit4] 1> 169857 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4c54f254 main
[junit4:junit4] 1> 169857 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 169857 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@4c54f254 main{StandardDirectoryReader(segments_b:21:nrt)}
[junit4:junit4] 1> 169858 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 169860 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@4c54f254 main{StandardDirectoryReader(segments_b:21:nrt)}
[junit4:junit4] 1> 169860 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4:junit4] 1> 169865 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 169876 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1437472673056686080), 2 (1437472673057734656), 3 (1437472673058783232), 4 (1437472673059831808), 5 (1437472673059831809), 6 (1437472673060880384), 7 (1437472673060880385), 8 (1437472673060880386), 9 (1437472673061928960), 10 (1437472673061928961), ... (12 adds)]} 0 5
[junit4:junit4] 1> 169880 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 169882 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_c,generation=12}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_d,generation=13}
[junit4:junit4] 1> 169882 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – newest commit generation = 13
[junit4:junit4] 1> 169884 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2def5621 main
[junit4:junit4] 1> 169885 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 169885 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2def5621 main{StandardDirectoryReader(segments_d:23:nrt _6(4.4):C12)}
[junit4:junit4] 1> 169885 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 169886 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2def5621 main{StandardDirectoryReader(segments_d:23:nrt _6(4.4):C12)}
[junit4:junit4] 1> 169888 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 169891 [qtp1164189016-461] 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> 169892 [qtp1164189016-461] 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> 169895 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet.missing=true&facet=true&facet.mincount=0&q=*:*&wt=javabin&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2&rows=0} hits=12 status=0 QTime=4
[junit4:junit4] 1> 169899 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=1
[junit4:junit4] 1> 169901 [TEST-SolrExampleJettyTest.testPivotFacetsMissing-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPivotFacetsMissing
[junit4:junit4] 1> 169909 [TEST-SolrExampleJettyTest.testContentStreamRequest-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testContentStreamRequest
[junit4:junit4] 1> 169914 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5bc5fd48 realtime
[junit4:junit4] 1> 169915 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472673100726272)} 0 3
[junit4:junit4] 1> 169918 [qtp1164189016-464] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 169919 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_d,generation=13}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_e,generation=14}
[junit4:junit4] 1> 169919 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – newest commit generation = 14
[junit4:junit4] 1> 169920 [qtp1164189016-464] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@645a7345 main
[junit4:junit4] 1> 169921 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@645a7345 main{StandardDirectoryReader(segments_d:25:nrt)}
[junit4:junit4] 1> 169921 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 169921 [qtp1164189016-464] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 169921 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@645a7345 main{StandardDirectoryReader(segments_d:25:nrt)}
[junit4:junit4] 1> 169923 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4:junit4] 1> 169926 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 169944 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 169950 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_e,generation=14}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_f,generation=15}
[junit4:junit4] 1> 169951 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – newest commit generation = 15
[junit4:junit4] 1> 169996 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@10dc65c0 main
[junit4:junit4] 1> 169997 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@10dc65c0 main{StandardDirectoryReader(segments_f:27:nrt _7(4.4):C10)}
[junit4:junit4] 1> 169997 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 169999 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@10dc65c0 main{StandardDirectoryReader(segments_f:27:nrt _7(4.4):C10)}
[junit4:junit4] 1> 170001 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170001 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0553573403 (1437472673120649216), 0553579908 (1437472673123794944), 055357342X (1437472673125892096), 0553293354 (1437472673125892097), 0812521390 (1437472673125892098), 0812550706 (1437472673126940672), 0441385532 (1437472673129037824), 0380014300 (1437472673130086400), 0805080481 (1437472673132183552), 080508049X (1437472673133232128)],commit=} 0 70
[junit4:junit4] 1> 170006 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4:junit4] 1> 170008 [TEST-SolrExampleJettyTest.testContentStreamRequest-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testContentStreamRequest
[junit4:junit4] 1> 170031 [TEST-SolrExampleJettyTest.testLukeHandler-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testLukeHandler
[junit4:junit4] 1> 170036 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3d99bc3a realtime
[junit4:junit4] 1> 170037 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472673228652544)} 0 4
[junit4:junit4] 1> 170043 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID0 (1437472673237041152)]} 0 1
[junit4:junit4] 1> 170048 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID1 (1437472673243332608)]} 0 0
[junit4:junit4] 1> 170054 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID2 (1437472673249624064)]} 0 1
[junit4:junit4] 1> 170059 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID3 (1437472673254866944)]} 0 1
[junit4:junit4] 1> 170064 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID4 (1437472673260109824)]} 0 0
[junit4:junit4] 1> 170069 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170072 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_f,generation=15}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_g,generation=16}
[junit4:junit4] 1> 170073 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – newest commit generation = 16
[junit4:junit4] 1> 170074 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5eb17728 main
[junit4:junit4] 1> 170076 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170077 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5eb17728 main{StandardDirectoryReader(segments_g:31:nrt _8(4.4):C5)}
[junit4:junit4] 1> 170078 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170079 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5eb17728 main{StandardDirectoryReader(segments_g:31:nrt _8(4.4):C5)}
[junit4:junit4] 1> 170081 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4:junit4] 1> 170089 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0
[junit4:junit4] 1> 170094 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/luke params={wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 1> 170099 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=javabin&version=2} status=0 QTime=1
[junit4:junit4] 1> 170127 [TEST-SolrExampleJettyTest.testLukeHandler-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testLukeHandler
[junit4:junit4] 1> 170144 [TEST-SolrExampleJettyTest.testUpdateMultiValuedField-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateMultiValuedField
[junit4:junit4] 1> 170151 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1437472673349238784)]} 0 3
[junit4:junit4] 1> 170155 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170161 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_g,generation=16}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_h,generation=17}
[junit4:junit4] 1> 170162 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – newest commit generation = 17
[junit4:junit4] 1> 170164 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@387f4217 main
[junit4:junit4] 1> 170166 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170166 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@387f4217 main{StandardDirectoryReader(segments_h:33:nrt _8(4.4):C5 _9(4.4):C1)}
[junit4:junit4] 1> 170184 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170186 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@387f4217 main{StandardDirectoryReader(segments_h:33:nrt _8(4.4):C5 _9(4.4):C1)}
[junit4:junit4] 1> 170186 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 31
[junit4:junit4] 1> 170192 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 170201 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1437472673399570432)]} 0 4
[junit4:junit4] 1> 170205 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170208 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_h,generation=17}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_i,generation=18}
[junit4:junit4] 1> 170209 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – newest commit generation = 18
[junit4:junit4] 1> 170211 [qtp1164189016-466] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6c439d7a main
[junit4:junit4] 1> 170212 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170213 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6c439d7a main{StandardDirectoryReader(segments_i:37:nrt _8(4.4):C5 _a(4.4):C1)}
[junit4:junit4] 1> 170213 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170216 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6c439d7a main{StandardDirectoryReader(segments_i:37:nrt _8(4.4):C5 _a(4.4):C1)}
[junit4:junit4] 1> 170259 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 54
[junit4:junit4] 1> 170263 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 170268 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1437472673474019328)]} 0 1
[junit4:junit4] 1> 170272 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170276 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_i,generation=18}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_j,generation=19}
[junit4:junit4] 1> 170277 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – newest commit generation = 19
[junit4:junit4] 1> 170278 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@a1a0409 main
[junit4:junit4] 1> 170280 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170280 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@a1a0409 main{StandardDirectoryReader(segments_j:41:nrt _8(4.4):C5 _b(4.4):C1)}
[junit4:junit4] 1> 170282 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170282 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@a1a0409 main{StandardDirectoryReader(segments_j:41:nrt _8(4.4):C5 _b(4.4):C1)}
[junit4:junit4] 1> 170283 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 170288 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 170291 [TEST-SolrExampleJettyTest.testUpdateMultiValuedField-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUpdateMultiValuedField
[junit4:junit4] 1> 170298 [TEST-SolrExampleJettyTest.testSetNullUpdates-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testSetNullUpdates
[junit4:junit4] 1> 170304 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1437472673509670912)]} 0 3
[junit4:junit4] 1> 170309 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170312 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_j,generation=19}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_k,generation=20}
[junit4:junit4] 1> 170313 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – newest commit generation = 20
[junit4:junit4] 1> 170314 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@37d1b92 main
[junit4:junit4] 1> 170315 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@37d1b92 main{StandardDirectoryReader(segments_k:43:nrt _8(4.4):C5 _b(4.4):C1 _c(4.4):C1)}
[junit4:junit4] 1> 170315 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170316 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170316 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@37d1b92 main{StandardDirectoryReader(segments_k:43:nrt _8(4.4):C5 _b(4.4):C1 _c(4.4):C1)}
[junit4:junit4] 1> 170318 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4] 1> 170323 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1437472673529593856)]} 0 2
[junit4:junit4] 1> 170328 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170330 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_k,generation=20}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_l,generation=21}
[junit4:junit4] 1> 170331 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – newest commit generation = 21
[junit4:junit4] 1> 170332 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5ca969ac main
[junit4:junit4] 1> 170333 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5ca969ac main{StandardDirectoryReader(segments_l:47:nrt _8(4.4):C5 _b(4.4):C1 _d(4.4):C1)}
[junit4:junit4] 1> 170333 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170333 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170335 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5ca969ac main{StandardDirectoryReader(segments_l:47:nrt _8(4.4):C5 _b(4.4):C1 _d(4.4):C1)}
[junit4:junit4] 1> 170336 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 170341 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 170342 [TEST-SolrExampleJettyTest.testSetNullUpdates-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testSetNullUpdates
[junit4:junit4] 1> 170354 [TEST-SolrExampleJettyTest.testAddRetrieve-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testAddRetrieve
[junit4:junit4] 1> 170359 [qtp1164189016-464] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@775feb51 realtime
[junit4:junit4] 1> 170360 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472673568391168)} 0 2
[junit4:junit4] 1> 170363 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1437472673573634048), id2 (1437472673573634049)]} 0 0
[junit4:junit4] 1> 170368 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170371 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_l,generation=21}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_m,generation=22}
[junit4:junit4] 1> 170372 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – newest commit generation = 22
[junit4:junit4] 1> 170374 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@43e0522 main
[junit4:junit4] 1> 170375 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@43e0522 main{StandardDirectoryReader(segments_m:51:nrt _e(4.4):C2)}
[junit4:junit4] 1> 170375 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170376 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170379 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@43e0522 main{StandardDirectoryReader(segments_m:51:nrt _e(4.4):C2)}
[junit4:junit4] 1> 170381 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4:junit4] 1> 170385 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4:junit4] 1> 170393 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1437472673600897024), id2 (1437472673604042752)]} 0 4
[junit4:junit4] 1> 170397 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170402 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_m,generation=22}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_n,generation=23}
[junit4:junit4] 1> 170402 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – newest commit generation = 23
[junit4:junit4] 1> 170405 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@61f0f955 main
[junit4:junit4] 1> 170405 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@61f0f955 main{StandardDirectoryReader(segments_n:55:nrt _f(4.4):C2)}
[junit4:junit4] 1> 170405 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170406 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170407 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@61f0f955 main{StandardDirectoryReader(segments_n:55:nrt _f(4.4):C2)}
[junit4:junit4] 1> 170408 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 170413 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4:junit4] 1> 170417 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={sort=price+asc&q=name:h?llo&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 170420 [TEST-SolrExampleJettyTest.testAddRetrieve-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAddRetrieve
[junit4:junit4] 1> 170431 [TEST-SolrExampleJettyTest.testRealtimeGet-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testRealtimeGet
[junit4:junit4] 1> 170443 [qtp1164189016-461] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1f1eaa09 realtime
[junit4:junit4] 1> 170444 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472673649131520)} 0 9
[junit4:junit4] 1> 170448 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[DOCID (1437472673661714432)]} 0 1
[junit4:junit4] 1> 170452 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170456 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_n,generation=23}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_o,generation=24}
[junit4:junit4] 1> 170456 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – newest commit generation = 24
[junit4:junit4] 1> 170457 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@65b051a5 main
[junit4:junit4] 1> 170460 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@65b051a5 main{StandardDirectoryReader(segments_o:59:nrt _g(4.4):C1)}
[junit4:junit4] 1> 170460 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170460 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170462 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@65b051a5 main{StandardDirectoryReader(segments_o:59:nrt _g(4.4):C1)}
[junit4:junit4] 1> 170464 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4:junit4] 1> 170467 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=javabin&qt=/get&version=2} status=0 QTime=0
[junit4:junit4] 1> 170472 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=xml&qt=/get&version=2.2} status=0 QTime=1
[junit4:junit4] 1> 170474 [TEST-SolrExampleJettyTest.testRealtimeGet-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testRealtimeGet
[junit4:junit4] 1> 170486 [TEST-SolrExampleJettyTest.testGetEmptyResults-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testGetEmptyResults
[junit4:junit4] 1> 170491 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6fcca5f9 realtime
[junit4:junit4] 1> 170491 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472673705754624)} 0 2
[junit4:junit4] 1> 170495 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170495 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_o,generation=24}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_p,generation=25}
[junit4:junit4] 1> 170496 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – newest commit generation = 25
[junit4:junit4] 1> 170496 [qtp1164189016-461] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@65aec411 main
[junit4:junit4] 1> 170496 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170497 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@65aec411 main{StandardDirectoryReader(segments_o:61:nrt)}
[junit4:junit4] 1> 170498 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170500 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@65aec411 main{StandardDirectoryReader(segments_o:61:nrt)}
[junit4:junit4] 1> 170500 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4:junit4] 1> 170506 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1437472673721483264)]} 0 3
[junit4:junit4] 1> 170510 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id2 (1437472673727774720)]} 0 1
[junit4:junit4] 1> 170514 [qtp1164189016-464] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170516 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_p,generation=25}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_q,generation=26}
[junit4:junit4] 1> 170517 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – newest commit generation = 26
[junit4:junit4] 1> 170518 [qtp1164189016-464] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5f0fa06 main
[junit4:junit4] 1> 170519 [qtp1164189016-464] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170520 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5f0fa06 main{StandardDirectoryReader(segments_q:63:nrt _h(4.4):C2)}
[junit4:junit4] 1> 170520 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170522 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5f0fa06 main{StandardDirectoryReader(segments_q:63:nrt _h(4.4):C2)}
[junit4:junit4] 1> 170522 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4] 1> 170527 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=foofoofoo&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4:junit4] 1> 170527 [TEST-SolrExampleJettyTest.testGetEmptyResults-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testGetEmptyResults
[junit4:junit4] 1> 170538 [TEST-SolrExampleJettyTest.testUpdateField-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateField
[junit4:junit4] 1> 170542 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4c40301f realtime
[junit4:junit4] 1> 170543 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472673760280576)} 0 2
[junit4:junit4] 1> 170547 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170547 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_q,generation=26}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_r,generation=27}
[junit4:junit4] 1> 170547 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – newest commit generation = 27
[junit4:junit4] 1> 170549 [qtp1164189016-461] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@33ad48e7 main
[junit4:junit4] 1> 170551 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170551 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@33ad48e7 main{StandardDirectoryReader(segments_q:65:nrt)}
[junit4:junit4] 1> 170552 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170554 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@33ad48e7 main{StandardDirectoryReader(segments_q:65:nrt)}
[junit4:junit4] 1> 170555 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 170560 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1437472673778106368)]} 0 2
[junit4:junit4] 1> 170564 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170566 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_r,generation=27}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_s,generation=28}
[junit4:junit4] 1> 170567 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – newest commit generation = 28
[junit4:junit4] 1> 170568 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4e18b2cb main
[junit4:junit4] 1> 170568 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@4e18b2cb main{StandardDirectoryReader(segments_s:67:nrt _i(4.4):C1)}
[junit4:junit4] 1> 170568 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170569 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170570 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@4e18b2cb main{StandardDirectoryReader(segments_s:67:nrt _i(4.4):C1)}
[junit4:junit4] 1> 170572 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 170575 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 170579 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 0
[junit4:junit4] 1> 170586 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1437472673805369344)]} 0 2
[junit4:junit4] 1> 170590 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170593 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_s,generation=28}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_t,generation=29}
[junit4:junit4] 1> 170593 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – newest commit generation = 29
[junit4:junit4] 1> 170594 [qtp1164189016-461] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6db81907 main
[junit4:junit4] 1> 170595 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6db81907 main{StandardDirectoryReader(segments_t:71:nrt _j(4.4):C1)}
[junit4:junit4] 1> 170595 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170596 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170598 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6db81907 main{StandardDirectoryReader(segments_t:71:nrt _j(4.4):C1)}
[junit4:junit4] 1> 170598 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 170603 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 170608 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1437472673829486592)]} 0 1
[junit4:junit4] 1> 170613 [qtp1164189016-464] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170615 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_t,generation=29}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_u,generation=30}
[junit4:junit4] 1> 170617 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – newest commit generation = 30
[junit4:junit4] 1> 170619 [qtp1164189016-464] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@d51df63 main
[junit4:junit4] 1> 170620 [qtp1164189016-464] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170621 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@d51df63 main{StandardDirectoryReader(segments_u:75:nrt _k(4.4):C1)}
[junit4:junit4] 1> 170621 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170624 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@d51df63 main{StandardDirectoryReader(segments_u:75:nrt _k(4.4):C1)}
[junit4:junit4] 1> 170624 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 170629 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 170629 [TEST-SolrExampleJettyTest.testUpdateField-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUpdateField
[junit4:junit4] 1> 170639 [TEST-SolrExampleJettyTest.testCommitWithinOnAdd-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testCommitWithinOnAdd
[junit4:junit4] 1> 170645 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@46c0a592 realtime
[junit4:junit4] 1> 170645 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472673868283904)} 0 2
[junit4:junit4] 1> 170649 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 170650 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_u,generation=30}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_v,generation=31}
[junit4:junit4] 1> 170650 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – newest commit generation = 31
[junit4:junit4] 1> 170651 [qtp1164189016-461] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@680b601d main
[junit4:junit4] 1> 170651 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@680b601d main{StandardDirectoryReader(segments_u:77:nrt)}
[junit4:junit4] 1> 170652 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 170654 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@680b601d main{StandardDirectoryReader(segments_u:77:nrt)}
[junit4:junit4] 1> 170654 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 170655 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4:junit4] 1> 170659 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 170664 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1437472673887158272)]} 0 3
[junit4:junit4] 1> 170668 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 171091 [commitScheduler-229-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4] 1> 171094 [commitScheduler-229-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5291a2dc main
[junit4:junit4] 1> 171095 [commitScheduler-229-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 171095 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5291a2dc main{StandardDirectoryReader(segments_v:79:nrt _l(4.4):C1)}
[junit4:junit4] 1> 171096 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 171097 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5291a2dc main{StandardDirectoryReader(segments_v:79:nrt _l(4.4):C1)}
[junit4:junit4] 1> 171529 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 171534 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id4 (1437472674800467968)]} 0 2
[junit4:junit4] 1> 171961 [commitScheduler-229-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4] 1> 172005 [commitScheduler-229-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1164a212 main
[junit4:junit4] 1> 172007 [commitScheduler-229-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172007 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1164a212 main{StandardDirectoryReader(segments_v:81:nrt _l(4.4):C1 _m(4.4):C1)}
[junit4:junit4] 1> 172008 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172014 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1164a212 main{StandardDirectoryReader(segments_v:81:nrt _l(4.4):C1 _m(4.4):C1)}
[junit4:junit4] 1> 172393 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:id4&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4:junit4] 1> 172395 [TEST-SolrExampleJettyTest.testCommitWithinOnAdd-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testCommitWithinOnAdd
[junit4:junit4] 1> 172405 [TEST-SolrExampleJettyTest.testSetNullUpdateOrder-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testSetNullUpdateOrder
[junit4:junit4] 1> 172410 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1437472675720069120)]} 0 0
[junit4:junit4] 1> 172415 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172417 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_v,generation=31}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_w,generation=32}
[junit4:junit4] 1> 172418 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – newest commit generation = 32
[junit4:junit4] 1> 172420 [qtp1164189016-461] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@43f88247 main
[junit4:junit4] 1> 172420 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@43f88247 main{StandardDirectoryReader(segments_w:83:nrt _l(4.4):C1 _m(4.4):C1 _n(4.4):C1)}
[junit4:junit4] 1> 172420 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172421 [qtp1164189016-461] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172421 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@43f88247 main{StandardDirectoryReader(segments_w:83:nrt _l(4.4):C1 _m(4.4):C1 _n(4.4):C1)}
[junit4:junit4] 1> 172423 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 172430 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1437472675737894912)]} 0 3
[junit4:junit4] 1> 172433 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172436 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_w,generation=32}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_x,generation=33}
[junit4:junit4] 1> 172436 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – newest commit generation = 33
[junit4:junit4] 1> 172437 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6581fec4 main
[junit4:junit4] 1> 172438 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6581fec4 main{StandardDirectoryReader(segments_x:87:nrt _l(4.4):C1 _m(4.4):C1 _o(4.4):C1)}
[junit4:junit4] 1> 172438 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172439 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172441 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6581fec4 main{StandardDirectoryReader(segments_x:87:nrt _l(4.4):C1 _m(4.4):C1 _o(4.4):C1)}
[junit4:junit4] 1> 172441 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 172445 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdateOrder&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4:junit4] 1> 172446 [TEST-SolrExampleJettyTest.testSetNullUpdateOrder-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testSetNullUpdateOrder
[junit4:junit4] 1> 172456 [TEST-SolrExampleJettyTest.testChineseDefaults-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testChineseDefaults
[junit4:junit4] 1> 172461 [qtp1164189016-464] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5db6004f realtime
[junit4:junit4] 1> 172462 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472675771449344)} 0 3
[junit4:junit4] 1> 172466 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172467 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_x,generation=33}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_y,generation=34}
[junit4:junit4] 1> 172468 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – newest commit generation = 34
[junit4:junit4] 1> 172470 [qtp1164189016-466] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@30b5c55e main
[junit4:junit4] 1> 172471 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@30b5c55e main{StandardDirectoryReader(segments_x:89:nrt)}
[junit4:junit4] 1> 172471 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172471 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172471 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@30b5c55e main{StandardDirectoryReader(segments_x:89:nrt)}
[junit4:junit4] 1> 172472 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4:junit4] 1> 172477 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 172484 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172487 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_y,generation=34}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_z,generation=35}
[junit4:junit4] 1> 172487 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – newest commit generation = 35
[junit4:junit4] 1> 172489 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7bb4f69c main
[junit4:junit4] 1> 172491 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@7bb4f69c main{StandardDirectoryReader(segments_z:91:nrt _p(4.4):C1)}
[junit4:junit4] 1> 172491 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172492 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172492 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@7bb4f69c main{StandardDirectoryReader(segments_z:91:nrt _p(4.4):C1)}
[junit4:junit4] 1> 172494 [qtp1164189016-465] 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 (1437472675795566592)],commit=} 0 12
[junit4:junit4] 1> 172500 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=????&wt=javabin&version=2} hits=1 status=0 QTime=2
[junit4:junit4] 1> 172501 [TEST-SolrExampleJettyTest.testChineseDefaults-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testChineseDefaults
[junit4:junit4] 1> 172513 [TEST-SolrExampleJettyTest.testPingHandler-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPingHandler
[junit4:junit4] 1> 172519 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4917ef4 realtime
[junit4:junit4] 1> 172520 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472675832266752)} 0 3
[junit4:junit4] 1> 172523 [qtp1164189016-464] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172524 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_z,generation=35}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_10,generation=36}
[junit4:junit4] 1> 172524 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – newest commit generation = 36
[junit4:junit4] 1> 172525 [qtp1164189016-464] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1e6ea7bb main
[junit4:junit4] 1> 172526 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1e6ea7bb main{StandardDirectoryReader(segments_z:93:nrt)}
[junit4:junit4] 1> 172527 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172527 [qtp1164189016-464] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172528 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1e6ea7bb main{StandardDirectoryReader(segments_z:93:nrt)}
[junit4:junit4] 1> 172529 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4:junit4] 1> 172534 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 172541 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 172541 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} status=0 QTime=1
[junit4:junit4] 1> 172542 [TEST-SolrExampleJettyTest.testPingHandler-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPingHandler
[junit4:junit4] 1> 172557 [TEST-SolrExampleJettyTest.testPivotFacets-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPivotFacets
[junit4:junit4] 1> 172563 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472675877355520)} 0 3
[junit4:junit4] 1> 172566 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172567 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_10,generation=36}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_11,generation=37}
[junit4:junit4] 1> 172568 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – newest commit generation = 37
[junit4:junit4] 1> 172568 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3a3f97bd main
[junit4:junit4] 1> 172569 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3a3f97bd main{StandardDirectoryReader(segments_z:93:nrt)}
[junit4:junit4] 1> 172569 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172570 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172572 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3a3f97bd main{StandardDirectoryReader(segments_z:93:nrt)}
[junit4:junit4] 1> 172573 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4:junit4] 1> 172576 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 172586 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1437472675898327040), 2 (1437472675901472768), 3 (1437472675901472769), 4 (1437472675901472770), 5 (1437472675901472771), 6 (1437472675901472772), 7 (1437472675902521344), 8 (1437472675903569920), 9 (1437472675903569921), 10 (1437472675903569922), ... (12 adds)]} 0 6
[junit4:junit4] 1> 172591 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172594 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_11,generation=37}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_12,generation=38}
[junit4:junit4] 1> 172595 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – newest commit generation = 38
[junit4:junit4] 1> 172597 [qtp1164189016-466] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@7aee03fe main
[junit4:junit4] 1> 172598 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@7aee03fe main{StandardDirectoryReader(segments_12:95:nrt _q(4.4):C12)}
[junit4:junit4] 1> 172598 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172599 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172599 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@7aee03fe main{StandardDirectoryReader(segments_12:95:nrt _q(4.4):C12)}
[junit4:junit4] 1> 172600 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4] 1> 172605 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=1,phase1=1,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4] 1> 172606 [qtp1164189016-461] 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> 172609 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet.missing=false&facet=true&facet.mincount=0&q=*:*&wt=javabin&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2&rows=0} hits=12 status=0 QTime=5
[junit4:junit4] 1> 172614 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=1
[junit4:junit4] 1> 172616 [TEST-SolrExampleJettyTest.testPivotFacets-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPivotFacets
[junit4:junit4] 1> 172631 [TEST-SolrExampleJettyTest.testUnicode-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUnicode
[junit4:junit4] 1> 172636 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6358f184 realtime
[junit4:junit4] 1> 172638 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1437472675954950144)} 0 4
[junit4:junit4] 1> 172650 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1437472675970678784), 1 (1437472675970678785), 2 (1437472675970678786), 3 (1437472675970678787), 4 (1437472675971727360)]} 0 1
[junit4:junit4] 1> 172655 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172658 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_12,generation=38}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_13,generation=39}
[junit4:junit4] 1> 172659 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – newest commit generation = 39
[junit4:junit4] 1> 172661 [qtp1164189016-466] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@60b53511 main
[junit4:junit4] 1> 172663 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@60b53511 main{StandardDirectoryReader(segments_13:99:nrt _r(4.4):C5)}
[junit4:junit4] 1> 172663 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172664 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172665 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@60b53511 main{StandardDirectoryReader(segments_13:99:nrt _r(4.4):C5)}
[junit4:junit4] 1> 172666 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 172670 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=5} hits=5 status=0 QTime=0
[junit4:junit4] 1> 172685 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@517b14df realtime
[junit4:junit4] 1> 172685 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472676005281792)} 0 3
[junit4:junit4] 1> 172692 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1437472676013670400)]} 0 1
[junit4:junit4] 1> 172696 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172699 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_13,generation=39}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_14,generation=40}
[junit4:junit4] 1> 172700 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – newest commit generation = 40
[junit4:junit4] 1> 172700 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@817d839 main
[junit4:junit4] 1> 172702 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@817d839 main{StandardDirectoryReader(segments_14:103:nrt _s(4.4):C1)}
[junit4:junit4] 1> 172703 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172705 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@817d839 main{StandardDirectoryReader(segments_14:103:nrt _s(4.4):C1)}
[junit4:junit4] 1> 172705 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172706 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4:junit4] 1> 172712 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=1} hits=1 status=0 QTime=1
[junit4:junit4] 1> 172719 [qtp1164189016-466] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@58591380 realtime
[junit4:junit4] 1> 172721 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472676041981952)} 0 5
[junit4:junit4] 1> 172731 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1437472676051419136), 1 (1437472676056662016), 2 (1437472676056662017)]} 0 5
[junit4:junit4] 1> 172736 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172737 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_14,generation=40}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_15,generation=41}
[junit4:junit4] 1> 172738 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – newest commit generation = 41
[junit4:junit4] 1> 172739 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3d87a249 main
[junit4:junit4] 1> 172740 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172741 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3d87a249 main{StandardDirectoryReader(segments_15:107:nrt _t(4.4):C3)}
[junit4:junit4] 1> 172741 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172743 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3d87a249 main{StandardDirectoryReader(segments_15:107:nrt _t(4.4):C3)}
[junit4:junit4] 1> 172744 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 172750 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=3} hits=3 status=0 QTime=1
[junit4:junit4] 1> 172751 [TEST-SolrExampleJettyTest.testUnicode-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUnicode
[junit4:junit4] 1> 172772 [TEST-SolrExampleJettyTest.testAugmentFields-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testAugmentFields
[junit4:junit4] 1> 172777 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@32c5889b realtime
[junit4:junit4] 1> 172779 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472676103847936)} 0 4
[junit4:junit4] 1> 172784 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1437472676111187968)]} 0 2
[junit4:junit4] 1> 172789 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172792 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_15,generation=41}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_16,generation=42}
[junit4:junit4] 1> 172793 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – newest commit generation = 42
[junit4:junit4] 1> 172794 [qtp1164189016-466] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@657d2dcf main
[junit4:junit4] 1> 172797 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172797 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@657d2dcf main{StandardDirectoryReader(segments_16:111:nrt _u(4.4):C1)}
[junit4:junit4] 1> 172798 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172800 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@657d2dcf main{StandardDirectoryReader(segments_16:111:nrt _u(4.4):C1)}
[junit4:junit4] 1> 172802 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4:junit4] 1> 172808 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222 (1437472676135305216)]} 0 2
[junit4:junit4] 1> 172815 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172818 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_16,generation=42}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_17,generation=43}
[junit4:junit4] 1> 172819 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – newest commit generation = 43
[junit4:junit4] 1> 172822 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@189cce59 main
[junit4:junit4] 1> 172825 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172827 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@189cce59 main{StandardDirectoryReader(segments_17:113:nrt _u(4.4):C1 _v(4.4):C1)}
[junit4:junit4] 1> 172827 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 172829 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@189cce59 main{StandardDirectoryReader(segments_17:113:nrt _u(4.4):C1 _v(4.4):C1)}
[junit4:junit4] 1> 172830 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4:junit4] 1> 172835 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={sort=price+asc&fl=id,price,[docid],[explain+style%3Dnl],score,aaa:[value+v%3Daaa],ten:[value+v%3D10+t%3Dint]&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4:junit4] 1> 172837 [TEST-SolrExampleJettyTest.testAugmentFields-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAugmentFields
[junit4:junit4] 1> 172855 [TEST-SolrExampleJettyTest.testStreamingRequest-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testStreamingRequest
[junit4:junit4] 1> 172861 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6d58dab5 realtime
[junit4:junit4] 1> 172862 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472676190879744)} 0 4
[junit4:junit4] 1> 172866 [qtp1164189016-464] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 172867 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_17,generation=43}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_18,generation=44}
[junit4:junit4] 1> 172868 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – newest commit generation = 44
[junit4:junit4] 1> 172868 [qtp1164189016-464] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@70f44ad5 main
[junit4:junit4] 1> 172870 [qtp1164189016-464] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 172872 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@70f44ad5 main{StandardDirectoryReader(segments_17:115:nrt)}
[junit4:junit4] 1> 172872 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 173037 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@70f44ad5 main{StandardDirectoryReader(segments_17:115:nrt)}
[junit4:junit4] 1> 173039 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 174
[junit4:junit4] 1> 173043 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=2
[junit4:junit4] 1> 173075 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 173078 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_18,generation=44}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_19,generation=45}
[junit4:junit4] 1> 173079 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – newest commit generation = 45
[junit4:junit4] 1> 173081 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3ab807ba main
[junit4:junit4] 1> 173082 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 173083 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3ab807ba main{StandardDirectoryReader(segments_19:117:nrt _w(4.4):C10)}
[junit4:junit4] 1> 173083 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 173086 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3ab807ba main{StandardDirectoryReader(segments_19:117:nrt _w(4.4):C10)}
[junit4:junit4] 1> 173087 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0 (1437472676389060608), 1 (1437472676399546368), 2 (1437472676400594944), 3 (1437472676401643520), 4 (1437472676401643521), 5 (1437472676402692096), 6 (1437472676416323584), 7 (1437472676416323585), 8 (1437472676417372160), 9 (1437472676417372161)],commit=} 0 39
[junit4:junit4] 1> 173092 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4:junit4] 1> 173097 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4:junit4] 1> 173097 [TEST-SolrExampleJettyTest.testStreamingRequest-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testStreamingRequest
[junit4:junit4] 1> 173110 [TEST-SolrExampleJettyTest.testStatistics-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testStatistics
[junit4:junit4] 1> 173116 [qtp1164189016-464] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2a39d896 realtime
[junit4:junit4] 1> 173117 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472676458266624)} 0 4
[junit4:junit4] 1> 173122 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 173122 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_19,generation=45}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1a,generation=46}
[junit4:junit4] 1> 173123 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – newest commit generation = 46
[junit4:junit4] 1> 173124 [qtp1164189016-466] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5510808f main
[junit4:junit4] 1> 173126 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 173126 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5510808f main{StandardDirectoryReader(segments_19:119:nrt)}
[junit4:junit4] 1> 173126 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 173128 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5510808f main{StandardDirectoryReader(segments_19:119:nrt)}
[junit4:junit4] 1> 173129 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 173133 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 173141 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1437472676483432448)]} 0 3
[junit4:junit4] 1> 173146 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1437472676490772480)]} 0 1
[junit4:junit4] 1> 173151 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1437472676496015360)]} 0 1
[junit4:junit4] 1> 173157 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1437472676503355392)]} 0 1
[junit4:junit4] 1> 173163 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1437472676509646848)]} 0 1
[junit4:junit4] 1> 173169 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1437472676514889728)]} 0 1
[junit4:junit4] 1> 173174 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1437472676521181184)]} 0 0
[junit4:junit4] 1> 173179 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1437472676526424064)]} 0 1
[junit4:junit4] 1> 173185 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1437472676531666944)]} 0 1
[junit4:junit4] 1> 173191 [qtp1164189016-464] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1437472676537958400)]} 0 1
[junit4:junit4] 1> 173196 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 173199 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1a,generation=46}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1b,generation=47}
[junit4:junit4] 1> 173199 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – newest commit generation = 47
[junit4:junit4] 1> 173202 [qtp1164189016-466] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2ccf4bf9 main
[junit4:junit4] 1> 173204 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 173205 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@2ccf4bf9 main{StandardDirectoryReader(segments_1b:121:nrt _x(4.4):C10)}
[junit4:junit4] 1> 173205 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 173208 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@2ccf4bf9 main{StandardDirectoryReader(segments_1b:121:nrt _x(4.4):C10)}
[junit4:junit4] 1> 173209 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4:junit4] 1> 173214 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4:junit4] 1> 173386 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=1
[junit4:junit4] 1> 173397 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@139d77ed realtime
[junit4:junit4] 1> 173398 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472676751867904)} 0 4
[junit4:junit4] 1> 173402 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 173403 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1b,generation=47}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1c,generation=48}
[junit4:junit4] 1> 173403 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – newest commit generation = 48
[junit4:junit4] 1> 173404 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1f04e1c2 main
[junit4:junit4] 1> 173406 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 173407 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1f04e1c2 main{StandardDirectoryReader(segments_1b:123:nrt)}
[junit4:junit4] 1> 173408 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 173409 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1f04e1c2 main{StandardDirectoryReader(segments_1b:123:nrt)}
[junit4:junit4] 1> 173410 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4:junit4] 1> 173415 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 173420 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc10 (1437472676777033728)]} 0 2
[junit4:junit4] 1> 173425 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc11 (1437472676784373760)]} 0 1
[junit4:junit4] 1> 173432 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc12 (1437472676790665216)]} 0 1
[junit4:junit4] 1> 173438 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc13 (1437472676796956672)]} 0 1
[junit4:junit4] 1> 173443 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc14 (1437472676803248128)]} 0 1
[junit4:junit4] 1> 173449 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 173452 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1c,generation=48}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1d,generation=49}
[junit4:junit4] 1> 173453 [qtp1164189016-466] INFO org.apache.solr.core.SolrCore – newest commit generation = 49
[junit4:junit4] 1> 173498 [qtp1164189016-466] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@402174fd main
[junit4:junit4] 1> 173500 [qtp1164189016-466] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 173501 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@402174fd main{StandardDirectoryReader(segments_1d:125:nrt _y(4.4):C5)}
[junit4:junit4] 1> 173502 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 173504 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@402174fd main{StandardDirectoryReader(segments_1d:125:nrt _y(4.4):C5)}
[junit4:junit4] 1> 173505 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 56
[junit4:junit4] 1> 173510 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=1
[junit4:junit4] 1> 173515 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=5 status=0 QTime=0
[junit4:junit4] 1> 173528 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3d43ebec realtime
[junit4:junit4] 1> 173529 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1437472676883988480)} 0 9
[junit4:junit4] 1> 173532 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 173533 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1d,generation=49}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1e,generation=50}
[junit4:junit4] 1> 173533 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – newest commit generation = 50
[junit4:junit4] 1> 173535 [qtp1164189016-463] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1e29337d main
[junit4:junit4] 1> 173536 [qtp1164189016-463] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 173537 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1e29337d main{StandardDirectoryReader(segments_1d:127:nrt)}
[junit4:junit4] 1> 173537 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 173540 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1e29337d main{StandardDirectoryReader(segments_1d:127:nrt)}
[junit4:junit4] 1> 173540 [qtp1164189016-463] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4:junit4] 1> 173545 [qtp1164189016-464] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4:junit4] 1> 173562 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1437472676915445760)]} 0 12
[junit4:junit4] 1> 173567 [qtp1164189016-461] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1437472676933271552)]} 0 1
[junit4:junit4] 1> 173573 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1437472676939563008)]} 0 1
[junit4:junit4] 1> 173579 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1437472676944805888)]} 0 2
[junit4:junit4] 1> 187569 [commitScheduler-228-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 187571 [commitScheduler-228-thread-1] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1e,generation=50}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1f,generation=51}
[junit4:junit4] 1> 187571 [commitScheduler-228-thread-1] INFO org.apache.solr.core.SolrCore – newest commit generation = 51
[junit4:junit4] 1> 187573 [commitScheduler-228-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1cbd41f5 realtime
[junit4:junit4] 1> 187575 [commitScheduler-228-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 373736 [TEST-SolrExampleJettyTest.testStatistics-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testStatistics
[junit4:junit4] 1> 373739 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1437472886825680896)]} 0 3
[junit4:junit4] 1> 373740 [qtp1164189016-465] ERROR org.apache.solr.servlet.SolrDispatchFilter – null:org.eclipse.jetty.io.EofException
[junit4:junit4] 1> at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:142)
[junit4:junit4] 1> at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:107)
[junit4:junit4] 1> at org.apache.solr.common.util.FastOutputStream.flush(FastOutputStream.java:214)
[junit4:junit4] 1> at org.apache.solr.common.util.FastOutputStream.flushBuffer(FastOutputStream.java:207)
[junit4:junit4] 1> at org.apache.solr.common.util.JavaBinCodec.marshal(JavaBinCodec.java:94)
[junit4:junit4] 1> at org.apache.solr.response.BinaryResponseWriter.write(BinaryResponseWriter.java:50)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.writeResponse(SolrDispatchFilter.java:637)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:372)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:155)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4] 1> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4:junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4:junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4] 1> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4:junit4] 1> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4:junit4] 1> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4:junit4] 1> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4] 1> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 1>
[junit4:junit4] 1> 373742 [qtp1164189016-465] ERROR org.apache.solr.servlet.SolrDispatchFilter – null:org.eclipse.jetty.io.EofException
[junit4:junit4] 1> at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:142)
[junit4:junit4] 1> at org.eclipse.jetty.server.HttpOutput.write(HttpOutput.java:107)
[junit4:junit4] 1> at org.apache.solr.common.util.FastOutputStream.flush(FastOutputStream.java:214)
[junit4:junit4] 1> at org.apache.solr.common.util.FastOutputStream.flushBuffer(FastOutputStream.java:207)
[junit4:junit4] 1> at org.apache.solr.common.util.JavaBinCodec.marshal(JavaBinCodec.java:94)
[junit4:junit4] 1> at org.apache.solr.response.BinaryResponseWriter.write(BinaryResponseWriter.java:50)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.writeResponse(SolrDispatchFilter.java:637)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:372)
[junit4:junit4] 1> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:155)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4] 1> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:135)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
[junit4:junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:229)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.GzipHandler.handle(GzipHandler.java:301)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1077)
[junit4:junit4] 1> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
[junit4:junit4] 1> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
[junit4:junit4] 1> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
[junit4:junit4] 1> at org.eclipse.jetty.server.Server.handle(Server.java:368)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
[junit4:junit4] 1> at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
[junit4:junit4] 1> at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
[junit4:junit4] 1> at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
[junit4:junit4] 1> at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SslConnection.handle(SslConnection.java:196)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:628)
[junit4:junit4] 1> at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:52)
[junit4:junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
[junit4:junit4] 1> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
[junit4:junit4] 1> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 1>
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleJettyTest -Dtests.method=testStatistics -Dtests.seed=327B3BECBFB7CA61 -Dtests.slow=true -Dtests.locale=th_TH -Dtests.timezone=US/Hawaii -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR 201s | SolrExampleJettyTest.testStatistics <<<
[junit4:junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:53798/solr/collection1
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([327B3BECBFB7CA61:C5D20888139A1089]:0)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4:junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:861)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]
[...truncated too long message...]
kFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1m,generation=58}
[junit4:junit4] 1> 374201 [qtp1164189016-465] INFO org.apache.solr.core.SolrCore – newest commit generation = 58
[junit4:junit4] 1> 374203 [qtp1164189016-465] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@8c1c614 main
[junit4:junit4] 1> 374205 [qtp1164189016-465] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 374205 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@8c1c614 main{StandardDirectoryReader(segments_1m:147:nrt _14(4.4):C3)}
[junit4:junit4] 1> 374206 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 374208 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@8c1c614 main{StandardDirectoryReader(segments_1m:147:nrt _14(4.4):C3)}
[junit4:junit4] 1> 374209 [qtp1164189016-465] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4:junit4] 1> 374214 [qtp1164189016-461] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=1
[junit4:junit4] 1> 374219 [qtp1164189016-466] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1437472887330045952), 1 & 222 (-1437472887332143104), 2 & 222 (-1437472887332143105)]} 0 2
[junit4:junit4] 1> 374227 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 374228 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1m,generation=58}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@58380073 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1ca33d03),segFN=segments_1n,generation=59}
[junit4:junit4] 1> 374229 [qtp1164189016-459] INFO org.apache.solr.core.SolrCore – newest commit generation = 59
[junit4:junit4] 1> 374229 [qtp1164189016-459] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1d3b9fe0 main
[junit4:junit4] 1> 374232 [qtp1164189016-459] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 374236 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1d3b9fe0 main{StandardDirectoryReader(segments_1n:149:nrt)}
[junit4:junit4] 1> 374236 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 374238 [searcherExecutor-226-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1d3b9fe0 main{StandardDirectoryReader(segments_1n:149:nrt)}
[junit4:junit4] 1> 374240 [qtp1164189016-459] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4:junit4] 1> 374245 [qtp1164189016-463] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4:junit4] 1> 374246 [TEST-SolrExampleJettyTest.testAddDelete-seed#[327B3BECBFB7CA61]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAddDelete
[junit4:junit4] 1> 374260 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.CoreContainer – Shutting down CoreContainer instance=1255881692
[junit4:junit4] 1> 374260 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCore – [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@74ab0af4
[junit4:junit4] 1> 374283 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.update.UpdateHandler – closing DirectUpdateHandler2{commits=59,autocommit maxTime=15000ms,autocommits=1,soft autocommits=3,optimizes=2,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=128,cumulative_deletesById=5,cumulative_deletesByQuery=27,cumulative_errors=1}
[junit4:junit4] 1> 374284 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.update.SolrCoreState – Closing SolrCoreState
[junit4:junit4] 1> 374284 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.update.DefaultSolrCoreState – SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 1> 374284 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.update.DefaultSolrCoreState – closing IndexWriter with IndexWriterCloser
[junit4:junit4] 1> 374290 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.SolrCore – [collection1] Closing main searcher on request.
[junit4:junit4] 1> 374290 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 1> 374291 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\index;done=false>>]
[junit4:junit4] 1> 374291 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\index
[junit4:junit4] 1> 374291 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\;done=false>>]
[junit4:junit4] 1> 374292 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-solrj\test\J0\solrtest-SolrExampleJettyTest-1370880766141\
[junit4:junit4] 1> 374293 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.eclipse.jetty.server.handler.ContextHandler – stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 1> 374348 [SUITE-SolrExampleJettyTest-seed#[327B3BECBFB7CA61]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=472), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=472)), sim=DefaultSimilarity, locale=th_TH, timezone=US/Hawaii
[junit4:junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_21 (64-bit)/cpus=2,threads=1,free=25095392,total=66965504
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestSpellCheckResponse, SolrExampleEmbeddedTest, ClientUtilsTest, SolrExampleXMLTest, ContentStreamTest, SolrPingTest, LargeVolumeEmbeddedTest, FieldAnalysisResponseTest, TestFastInputStream, TestCoreAdmin, TestUpdateRequestCodec, NamedListTest, TestXMLEscaping, TermsResponseTest, SolrDocumentTest, FacetFieldTest, JettyWebappTest, QueryResponseTest, DocumentAnalysisResponseTest, CloudSolrServerTest, SolrExampleStreamingTest, TestLBHttpSolrServer, SolrExampleStreamingBinaryTest, SolrExampleBinaryTest, SolrExampleJettyTest]
[junit4:junit4] Completed in 207.22s, 27 tests, 1 error <<< FAILURES!
[...truncated 68 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:391: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:371: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:194: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:437: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1243: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:887: There were test failures: 46 suites, 272 tests, 1 error, 1 ignored (1 assumption)
Total time: 72 minutes 36 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_21 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure