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/18 09:27:52 UTC
[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0-ea-b93) - Build #
6188 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/6188/
Java: 64bit/jdk1.8.0-ea-b93 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest.testAddRetrieve
Error Message:
IOException occured when talking to server at: https://127.0.0.1:41458/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:41458/solr/collection1
at __randomizedtesting.SeedInfo.seed([BA1B2474D2DE77FB:59C8B5D3E9615D42]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:436)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:168)
at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
at org.apache.solr.client.solrj.SolrExampleTests.testAddRetrieve(SolrExampleTests.java:255)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
... 45 more
Build Log:
[...truncated 10576 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest
[junit4:junit4] 1> 7899 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.SolrJettyTestBase ? Randomized ssl (true) and clientAuth (false)
[junit4:junit4] 1> 7899 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ####initCore
[junit4:junit4] 1> 7900 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ####initCore end
[junit4:junit4] 1> 7906 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.eclipse.jetty.server.Server ? jetty-8.1.10.v20130312
[junit4:junit4] 1> 8196 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-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> 8203 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.eclipse.jetty.server.AbstractConnector ? Started SslSelectChannelConnector@127.0.0.1:41458
[junit4:junit4] 1> 8205 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? SolrDispatchFilter.init()
[junit4:junit4] 1> 8206 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrResourceLoader ? JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 1> 8206 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrResourceLoader ? using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr
[junit4:junit4] 1> 8207 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.CoreContainer ? looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/solr.xml
[junit4:junit4] 1> 8207 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.CoreContainer ? New CoreContainer 1382721656
[junit4:junit4] 1> 8208 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.CoreContainer ? Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/'
[junit4:junit4] 1> 8209 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrResourceLoader ? new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/'
[junit4:junit4] 1> 8303 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr
[junit4:junit4] 1> 8305 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Discovered properties file /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/core.properties, adding to cores
[junit4:junit4] 1> 8307 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn
[junit4:junit4] 1> 8308 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/prop-base
[junit4:junit4] 1> 8310 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/tmp
[junit4:junit4] 1> 8311 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/tmp/prop-base
[junit4:junit4] 1> 8312 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/tmp/text-base
[junit4:junit4] 1> 8314 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/tmp/props
[junit4:junit4] 1> 8315 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/text-base
[junit4:junit4] 1> 8317 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/.svn/props
[junit4:junit4] 1> 8321 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting socketTimeout to: 0
[junit4:junit4] 1> 8322 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting urlScheme to: http://
[junit4:junit4] 1> 8323 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting connTimeout to: 0
[junit4:junit4] 1> 8324 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maxConnectionsPerHost to: 20
[junit4:junit4] 1> 8325 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting corePoolSize to: 0
[junit4:junit4] 1> 8326 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maximumPoolSize to: 2147483647
[junit4:junit4] 1> 8327 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maxThreadIdleTime to: 5
[junit4:junit4] 1> 8327 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting sizeOfQueue to: -1
[junit4:junit4] 1> 8328 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting fairnessPolicy to: false
[junit4:junit4] 1> 8329 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-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> 8334 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.logging.LogWatcher ? Registering Log Listener
[junit4:junit4] 1> 8338 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.CoreContainer ? Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1
[junit4:junit4] 1> 8339 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrResourceLoader ? new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/'
[junit4:junit4] 1> 8398 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrConfig ? Adding specified lib dirs to ClassLoader
[junit4:junit4] 1> 8400 [coreLoadExecutor-28-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4:junit4] 1> 8401 [coreLoadExecutor-28-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 8402 [coreLoadExecutor-28-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4:junit4] 1> 8403 [coreLoadExecutor-28-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 8404 [coreLoadExecutor-28-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4:junit4] 1> 8405 [coreLoadExecutor-28-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 8406 [coreLoadExecutor-28-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4:junit4] 1> 8406 [coreLoadExecutor-28-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/../../../dist).
[junit4:junit4] 1> 8543 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrConfig ? Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 1> 8655 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.Config ? Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 1> 8656 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.schema.IndexSchema ? Reading Solr Schema from schema.xml
[junit4:junit4] 1> 8737 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.schema.IndexSchema ? [collection1] Schema name=example
[junit4:junit4] 1> 9869 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.schema.IndexSchema ? unique key field: id
[junit4:junit4] 1> 9933 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider ? Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 9949 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider ? Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 10128 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 10130 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153/
[junit4:junit4] 1> 10132 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.JmxMonitoredMap ? JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@728c8a80
[junit4:junit4] 1> 10144 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4] 1> 10145 [coreLoadExecutor-28-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> 10152 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 10171 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory ? return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153
[junit4:junit4] 1> 10172 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153/index/
[junit4:junit4] 1> 10172 [coreLoadExecutor-28-thread-1] WARN org.apache.solr.core.SolrCore ? [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153/index' doesn't exist. Creating new index...
[junit4:junit4] 1> 10173 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory ? return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153/index
[junit4:junit4] 1> 10180 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1,generation=1}
[junit4:junit4] 1> 10181 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? newest commit generation = 1
[junit4:junit4] 1> 10188 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? created json: solr.JSONResponseWriter
[junit4:junit4] 1> 10189 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4] 1> 10190 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? created velocity: solr.VelocityResponseWriter
[junit4:junit4] 1> 10198 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? created xslt: solr.XSLTResponseWriter
[junit4:junit4] 1> 10199 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.response.XSLTResponseWriter ? xsltCacheLifetimeSeconds=5
[junit4:junit4] 1> 10283 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /select: solr.SearchHandler
[junit4:junit4] 1> 10285 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /query: solr.SearchHandler
[junit4:junit4] 1> 10287 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /get: solr.RealTimeGetHandler
[junit4:junit4] 1> 10288 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /browse: solr.SearchHandler
[junit4:junit4] 1> 10290 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update: solr.UpdateRequestHandler
[junit4:junit4] 1> 10298 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4] 1> 10303 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/csv: solr.CSVRequestHandler
[junit4:junit4] 1> 10304 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 10304 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 10305 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 10305 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 10306 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 10307 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 10314 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /admin/: solr.admin.AdminHandlers
[junit4:junit4] 1> 10316 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /admin/ping: solr.PingRequestHandler
[junit4:junit4] 1> 10323 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 1> 10339 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /replication: solr.ReplicationHandler
[junit4:junit4] 1> 10340 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 10341 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /spell: solr.SearchHandler
[junit4:junit4] 1> 10341 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 10342 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /tvrh: solr.SearchHandler
[junit4:junit4] 1> 10342 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 10343 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /terms: solr.SearchHandler
[junit4:junit4] 1> 10344 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 10344 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /elevate: solr.SearchHandler
[junit4:junit4] 1> 10347 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 10348 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 10349 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 10356 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3a5112cd main
[junit4:junit4] 1> 10362 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.update.UpdateHandler ? Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153/tlog
[junit4:junit4] 1> 10363 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? Hard AutoCommit: if uncommited for 15000ms;
[junit4:junit4] 1> 10364 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.SolrCore ? Soft AutoCommit: disabled
[junit4:junit4] 1> 10366 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Initializing spell checkers
[junit4:junit4] 1> 10386 [coreLoadExecutor-28-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> 10401 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? No queryConverter defined, using default converter
[junit4:junit4] 1> 10405 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.handler.component.QueryElevationComponent ? Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/solr/collection1/conf/elevate.xml
[junit4:junit4] 1> 10437 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.handler.ReplicationHandler ? Commits will be reserved for 10000
[junit4:junit4] 1> 10438 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3a5112cd main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 10464 [searcherExecutor-29-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=23
[junit4:junit4] 1> 10464 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 10465 [searcherExecutor-29-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Loading spell index for spellchecker: default
[junit4:junit4] 1> 10465 [searcherExecutor-29-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Loading spell index for spellchecker: wordbreak
[junit4:junit4] 1> 10471 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3a5112cd main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 10476 [coreLoadExecutor-28-thread-1] INFO org.apache.solr.core.CoreContainer ? registering core: collection1
[junit4:junit4] 1> 10479 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1
[junit4:junit4] 1> 10480 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? SolrDispatchFilter.init() done
[junit4:junit4] 1> 10481 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.SolrJettyTestBase ? Jetty Assigned Port#41458
[junit4:junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/./solrtest-SolrExampleJettyTest-1371540254153
[junit4:junit4] 1> 10503 [TEST-SolrExampleJettyTest.testLukeHandler-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testLukeHandler
[junit4:junit4] 1> 10504 [TEST-SolrExampleJettyTest.testLukeHandler-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 10651 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1,generation=1}
[junit4:junit4] 1> 10652 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 1
[junit4:junit4] 1> 10654 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@453f5580 realtime
[junit4:junit4] 1> 10656 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164196420222976)} 0 8
[junit4:junit4] 1> 10674 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[ID0 (1438164196437000192)]} 0 10
[junit4:junit4] 1> 10682 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[ID1 (1438164196454825984)]} 0 1
[junit4:junit4] 1> 10689 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[ID2 (1438164196462166016)]} 0 1
[junit4:junit4] 1> 10696 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[ID3 (1438164196469506048)]} 0 1
[junit4:junit4] 1> 10704 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[ID4 (1438164196477894656)]} 0 1
[junit4:junit4] 1> 10710 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 10720 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1,generation=1}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_2,generation=2}
[junit4:junit4] 1> 10721 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 2
[junit4:junit4] 1> 10729 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@644eeae5 main
[junit4:junit4] 1> 10732 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 10732 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@644eeae5 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):c5)}
[junit4:junit4] 1> 10733 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 10739 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@644eeae5 main{StandardDirectoryReader(segments_2:3:nrt _0(5.0):c5)}
[junit4:junit4] 1> 10741 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 31
[junit4:junit4] 1> 10747 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=5 status=0 QTime=1
[junit4:junit4] 1> 10766 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/luke params={version=2&wt=javabin} status=0 QTime=5
[junit4:junit4] 1> 10802 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/luke params={version=2&show=schema&wt=javabin} status=0 QTime=5
[junit4:junit4] 1> 10905 [TEST-SolrExampleJettyTest.testLukeHandler-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testLukeHandler
[junit4:junit4] 1> 10930 [TEST-SolrExampleJettyTest.testGetEmptyResults-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testGetEmptyResults
[junit4:junit4] 1> 10981 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@17c1882d realtime
[junit4:junit4] 1> 10982 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164196722212864)} 0 47
[junit4:junit4] 1> 10993 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 10994 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_2,generation=2}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_3,generation=3}
[junit4:junit4] 1> 10995 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 3
[junit4:junit4] 1> 10998 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7b07342a main
[junit4:junit4] 1> 10999 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@7b07342a main{StandardDirectoryReader(segments_2:5:nrt)}
[junit4:junit4] 1> 11000 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 10999 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11010 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@7b07342a main{StandardDirectoryReader(segments_2:5:nrt)}
[junit4:junit4] 1> 11014 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 22
[junit4:junit4] 1> 11026 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id1 (1438164196810293248)]} 0 6
[junit4:junit4] 1> 11033 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id2 (1438164196822876160)]} 0 1
[junit4:junit4] 1> 11038 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11046 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_3,generation=3}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_4,generation=4}
[junit4:junit4] 1> 11047 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 4
[junit4:junit4] 1> 11058 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6ea8338f main
[junit4:junit4] 1> 11059 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11059 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@6ea8338f main{StandardDirectoryReader(segments_4:7:nrt _1(5.0):c2)}
[junit4:junit4] 1> 11060 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11066 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@6ea8338f main{StandardDirectoryReader(segments_4:7:nrt _1(5.0):c2)}
[junit4:junit4] 1> 11068 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 30
[junit4:junit4] 1> 11087 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=foofoofoo&version=2&q=*:*&wt=javabin} hits=2 status=0 QTime=13
[junit4:junit4] 1> 11091 [TEST-SolrExampleJettyTest.testGetEmptyResults-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testGetEmptyResults
[junit4:junit4] 1> 11110 [TEST-SolrExampleJettyTest.testStatistics-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testStatistics
[junit4:junit4] 1> 11120 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@172a450e realtime
[junit4:junit4] 1> 11122 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164196909907968)} 0 7
[junit4:junit4] 1> 11127 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11129 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_4,generation=4}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_5,generation=5}
[junit4:junit4] 1> 11129 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 5
[junit4:junit4] 1> 11130 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2a6014d5 main
[junit4:junit4] 1> 11131 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2a6014d5 main{StandardDirectoryReader(segments_4:9:nrt)}
[junit4:junit4] 1> 11131 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11137 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2a6014d5 main{StandardDirectoryReader(segments_4:9:nrt)}
[junit4:junit4] 1> 11139 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11140 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 13
[junit4:junit4] 1> 11145 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 11154 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc0 (1438164196947656704)]} 0 4
[junit4:junit4] 1> 11161 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc1 (1438164196956045312)]} 0 2
[junit4:junit4] 1> 11168 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc2 (1438164196963385344)]} 0 2
[junit4:junit4] 1> 11174 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc3 (1438164196970725376)]} 0 1
[junit4:junit4] 1> 11181 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc4 (1438164196977016832)]} 0 2
[junit4:junit4] 1> 11187 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc5 (1438164196984356864)]} 0 1
[junit4:junit4] 1> 11193 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc6 (1438164196990648320)]} 0 1
[junit4:junit4] 1> 11200 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc7 (1438164196997988352)]} 0 2
[junit4:junit4] 1> 11207 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc8 (1438164197005328384)]} 0 2
[junit4:junit4] 1> 11213 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc9 (1438164197011619840)]} 0 1
[junit4:junit4] 1> 11218 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11225 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_5,generation=5}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_6,generation=6}
[junit4:junit4] 1> 11226 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 6
[junit4:junit4] 1> 11228 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@728ea954 main
[junit4:junit4] 1> 11229 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11229 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@728ea954 main{StandardDirectoryReader(segments_6:11:nrt _2(5.0):c10)}
[junit4:junit4] 1> 11230 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11236 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@728ea954 main{StandardDirectoryReader(segments_6:11:nrt _2(5.0):c10)}
[junit4:junit4] 1> 11237 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 19
[junit4:junit4] 1> 11243 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=10 status=0 QTime=1
[junit4:junit4] 1> 11277 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&rows=0&q=*:*&stats.field=val_pi&wt=javabin&stats=true} hits=10 status=0 QTime=28
[junit4:junit4] 1> 11288 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@223a0b8d realtime
[junit4:junit4] 1> 11289 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164197089214464)} 0 3
[junit4:junit4] 1> 11296 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11297 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_6,generation=6}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_7,generation=7}
[junit4:junit4] 1> 11298 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 7
[junit4:junit4] 1> 11304 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4b9093f0 main
[junit4:junit4] 1> 11306 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4b9093f0 main{StandardDirectoryReader(segments_6:13:nrt)}
[junit4:junit4] 1> 11306 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11307 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11314 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4b9093f0 main{StandardDirectoryReader(segments_6:13:nrt)}
[junit4:junit4] 1> 11317 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 21
[junit4:junit4] 1> 11322 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 1> 11333 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc10 (1438164197135351808)]} 0 3
[junit4:junit4] 1> 11339 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc11 (1438164197143740416)]} 0 2
[junit4:junit4] 1> 11345 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc12 (1438164197150031872)]} 0 1
[junit4:junit4] 1> 11352 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc13 (1438164197157371904)]} 0 2
[junit4:junit4] 1> 11358 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc14 (1438164197163663360)]} 0 1
[junit4:junit4] 1> 11363 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11369 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_7,generation=7}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_8,generation=8}
[junit4:junit4] 1> 11369 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 8
[junit4:junit4] 1> 11371 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3722ae2a main
[junit4:junit4] 1> 11373 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11373 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3722ae2a main{StandardDirectoryReader(segments_8:15:nrt _3(5.0):c5)}
[junit4:junit4] 1> 11374 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11380 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3722ae2a main{StandardDirectoryReader(segments_8:15:nrt _3(5.0):c5)}
[junit4:junit4] 1> 11382 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 19
[junit4:junit4] 1> 11387 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=5 status=0 QTime=0
[junit4:junit4] 1> 11394 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&rows=0&q=*:*&stats.field=val_pi&wt=javabin&stats=true} hits=5 status=0 QTime=1
[junit4:junit4] 1> 11401 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1e328e2f realtime
[junit4:junit4] 1> 11402 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164197207703552)} 0 3
[junit4:junit4] 1> 11408 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11410 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_8,generation=8}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_9,generation=9}
[junit4:junit4] 1> 11411 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 9
[junit4:junit4] 1> 11412 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@b7de90b main
[junit4:junit4] 1> 11413 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@b7de90b main{StandardDirectoryReader(segments_8:17:nrt)}
[junit4:junit4] 1> 11414 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11413 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11420 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@b7de90b main{StandardDirectoryReader(segments_8:17:nrt)}
[junit4:junit4] 1> 11422 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 14
[junit4:junit4] 1> 11427 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 11434 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc0 (1438164197243355136)]} 0 2
[junit4:junit4] 1> 11442 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc1 (1438164197250695168)]} 0 3
[junit4:junit4] 1> 11448 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc2 (1438164197258035200)]} 0 2
[junit4:junit4] 1> 11454 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc3 (1438164197264326656)]} 0 1
[junit4:junit4] 1> 11461 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc4 (1438164197271666688)]} 0 2
[junit4:junit4] 1> 11467 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc5 (1438164197277958144)]} 0 2
[junit4:junit4] 1> 11473 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc6 (1438164197284249600)]} 0 2
[junit4:junit4] 1> 11478 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc7 (1438164197289492480)]} 0 1
[junit4:junit4] 1> 11484 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc8 (1438164197295783936)]} 0 2
[junit4:junit4] 1> 11490 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc9 (1438164197302075392)]} 0 2
[junit4:junit4] 1> 11494 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11502 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_9,generation=9}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_a,generation=10}
[junit4:junit4] 1> 11502 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 10
[junit4:junit4] 1> 11504 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5a2a1c65 main
[junit4:junit4] 1> 11505 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11505 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5a2a1c65 main{StandardDirectoryReader(segments_a:19:nrt _4(5.0):c10)}
[junit4:junit4] 1> 11506 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11510 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5a2a1c65 main{StandardDirectoryReader(segments_a:19:nrt _4(5.0):c10)}
[junit4:junit4] 1> 11511 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 17
[junit4:junit4] 1> 11516 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=inStock:true&wt=javabin} hits=5 status=0 QTime=1
[junit4:junit4] 1> 11522 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=inStock:false&wt=javabin} hits=5 status=0 QTime=1
[junit4:junit4] 1> 11544 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&rows=0&q=*:*&f.val_pi.stats.facet=inStock&stats.field=val_pi&wt=javabin&stats=true} hits=10 status=0 QTime=17
[junit4:junit4] 1> 11546 [TEST-SolrExampleJettyTest.testStatistics-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testStatistics
[junit4:junit4] 1> 11587 [TEST-SolrExampleJettyTest.testContentStreamRequest-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testContentStreamRequest
[junit4:junit4] 1> 11596 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4abb575a realtime
[junit4:junit4] 1> 11597 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164197413224448)} 0 3
[junit4:junit4] 1> 11602 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11603 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_a,generation=10}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_b,generation=11}
[junit4:junit4] 1> 11604 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 11
[junit4:junit4] 1> 11604 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6652e270 main
[junit4:junit4] 1> 11606 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11606 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@6652e270 main{StandardDirectoryReader(segments_a:21:nrt)}
[junit4:junit4] 1> 11606 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11611 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@6652e270 main{StandardDirectoryReader(segments_a:21:nrt)}
[junit4:junit4] 1> 11612 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 10
[junit4:junit4] 1> 11617 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 1> 11661 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11676 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_b,generation=11}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_c,generation=12}
[junit4:junit4] 1> 11677 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 12
[junit4:junit4] 1> 11679 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6b25662c main
[junit4:junit4] 1> 11680 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@6b25662c main{StandardDirectoryReader(segments_c:23:nrt _5(5.0):c10)}
[junit4:junit4] 1> 11680 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11681 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11686 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@6b25662c main{StandardDirectoryReader(segments_c:23:nrt _5(5.0):c10)}
[junit4:junit4] 1> 11687 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {add=[0553573403 (1438164197458313216), 0553579908 (1438164197462507520), 055357342X (1438164197464604672), 0553293354 (1438164197466701824), 0812521390 (1438164197468798976), 0812550706 (1438164197470896128), 0441385532 (1438164197472993280), 0380014300 (1438164197475090432), 0805080481 (1438164197478236160), 080508049X (1438164197480333312)],commit=} 0 60
[junit4:junit4] 1> 11692 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=10 status=0 QTime=1
[junit4:junit4] 1> 11698 [TEST-SolrExampleJettyTest.testContentStreamRequest-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testContentStreamRequest
[junit4:junit4] 1> 11711 [TEST-SolrExampleJettyTest.testExampleConfig-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testExampleConfig
[junit4:junit4] 1> 11716 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@56b4979e realtime
[junit4:junit4] 1> 11717 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164197539053568)} 0 2
[junit4:junit4] 1> 11723 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1112211111 (1438164197546393600)]} 0 2
[junit4:junit4] 1> 11729 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11734 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_c,generation=12}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_d,generation=13}
[junit4:junit4] 1> 11734 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 13
[junit4:junit4] 1> 11736 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@17f071dc main
[junit4:junit4] 1> 11737 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@17f071dc main{StandardDirectoryReader(segments_d:27:nrt _6(5.0):c1)}
[junit4:junit4] 1> 11737 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11738 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11742 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@17f071dc main{StandardDirectoryReader(segments_d:27:nrt _6(5.0):c1)}
[junit4:junit4] 1> 11744 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 15
[junit4:junit4] 1> 11749 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11781 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_d,generation=13}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_e,generation=14}
[junit4:junit4] 1> 11782 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 14
[junit4:junit4] 1> 11785 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@56c1229c main
[junit4:junit4] 1> 11786 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11787 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@56c1229c main{StandardDirectoryReader(segments_e:29:nrt _7(5.0):C1)}
[junit4:junit4] 1> 11788 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11792 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@56c1229c main{StandardDirectoryReader(segments_e:29:nrt _7(5.0):C1)}
[junit4:junit4] 1> 11793 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={maxSegments=1&version=2&wt=javabin&waitSearcher=true&optimize=true} {optimize=} 0 45
[junit4:junit4] 1> 11798 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:1112211111&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 11814 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[2 (1438164197633425408), 3 (1438164197637619712), 4 (1438164197639716864), 5 (1438164197641814016)]} 0 10
[junit4:junit4] 1> 11819 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11828 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_e,generation=14}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_f,generation=15}
[junit4:junit4] 1> 11829 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 15
[junit4:junit4] 1> 11832 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@8a91e24 main
[junit4:junit4] 1> 11833 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@8a91e24 main{StandardDirectoryReader(segments_f:31:nrt _7(5.0):C1 _8(5.0):c4)}
[junit4:junit4] 1> 11834 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11834 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11839 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@8a91e24 main{StandardDirectoryReader(segments_f:31:nrt _7(5.0):C1 _8(5.0):c4)}
[junit4:junit4] 1> 11840 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 21
[junit4:junit4] 1> 11844 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11853 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_f,generation=15}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_g,generation=16}
[junit4:junit4] 1> 11853 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 16
[junit4:junit4] 1> 11857 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7228ec9e main
[junit4:junit4] 1> 11858 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11858 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@7228ec9e main{StandardDirectoryReader(segments_g:33:nrt _9(5.0):C5)}
[junit4:junit4] 1> 11859 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11863 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@7228ec9e main{StandardDirectoryReader(segments_g:33:nrt _9(5.0):C5)}
[junit4:junit4] 1> 11865 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={maxSegments=1&version=2&wt=javabin&waitSearcher=true&optimize=true} {optimize=} 0 21
[junit4:junit4] 1> 11916 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet.field=inStock&facet.field=price&facet.field=timestamp_dt&version=2&facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&wt=javabin} hits=5 status=0 QTime=46
[junit4:junit4] 1> 11933 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fq=inStock:true&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&version=2&facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&wt=javabin} hits=2 status=0 QTime=8
[junit4:junit4] 1> 11940 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fq={!field+f%3DinStock}true&fq={!term+f%3Dname}hoss&version=2&facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&q=foo&wt=javabin} hits=0 status=0 QTime=2
[junit4:junit4] 1> 11941 [TEST-SolrExampleJettyTest.testExampleConfig-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testExampleConfig
[junit4:junit4] 1> 11956 [TEST-SolrExampleJettyTest.testSetNullUpdateOrder-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testSetNullUpdateOrder
[junit4:junit4] 1> 11962 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdateOrder (1438164197795954688)]} 0 2
[junit4:junit4] 1> 11966 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 11971 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_g,generation=16}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_h,generation=17}
[junit4:junit4] 1> 11972 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 17
[junit4:junit4] 1> 11973 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@44467059 main
[junit4:junit4] 1> 11975 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@44467059 main{StandardDirectoryReader(segments_h:35:nrt _9(5.0):C5 _a(5.0):c1)}
[junit4:junit4] 1> 11975 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 11975 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 11980 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@44467059 main{StandardDirectoryReader(segments_h:35:nrt _9(5.0):C5 _a(5.0):c1)}
[junit4:junit4] 1> 11981 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 15
[junit4:junit4] 1> 12005 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdateOrder (1438164197841043456)]} 0 4
[junit4:junit4] 1> 12009 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 12014 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_h,generation=17}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_i,generation=18}
[junit4:junit4] 1> 12014 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 18
[junit4:junit4] 1> 12016 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@430b8c48 main
[junit4:junit4] 1> 12017 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@430b8c48 main{StandardDirectoryReader(segments_i:39:nrt _9(5.0):C5 _b(5.0):c1)}
[junit4:junit4] 1> 12018 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 12017 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 12022 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@430b8c48 main{StandardDirectoryReader(segments_i:39:nrt _9(5.0):C5 _b(5.0):c1)}
[junit4:junit4] 1> 12023 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 14
[junit4:junit4] 1> 12028 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:testSetNullUpdateOrder&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 12030 [TEST-SolrExampleJettyTest.testSetNullUpdateOrder-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testSetNullUpdateOrder
[junit4:junit4] 1> 12042 [TEST-SolrExampleJettyTest.testUpdateRequestWithParameters-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateRequestWithParameters
[junit4:junit4] 1> 12043 [TEST-SolrExampleJettyTest.testUpdateRequestWithParameters-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 12114 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@acb0339 realtime
[junit4:junit4] 1> 12115 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164197955338240)} 0 3
[junit4:junit4] 1> 12119 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 12120 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_i,generation=18}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_j,generation=19}
[junit4:junit4] 1> 12120 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 19
[junit4:junit4] 1> 12121 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4b9a0f0a main
[junit4:junit4] 1> 12122 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4b9a0f0a main{StandardDirectoryReader(segments_i:41:nrt)}
[junit4:junit4] 1> 12122 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 12122 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 12126 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4b9a0f0a main{StandardDirectoryReader(segments_i:41:nrt)}
[junit4:junit4] 1> 12128 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 9
[junit4:junit4] 1> 12133 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin&overwrite=false} {add=[id1 (1438164197976309760)]} 0 1
[junit4:junit4] 1> 12138 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin&overwrite=false} {add=[id1 (1438164197981552640)]} 0 1
[junit4:junit4] 1> 12142 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 12145 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_j,generation=19}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_k,generation=20}
[junit4:junit4] 1> 12146 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 20
[junit4:junit4] 1> 12147 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@69f199b main
[junit4:junit4] 1> 12148 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@69f199b main{StandardDirectoryReader(segments_k:43:nrt _c(5.0):c2)}
[junit4:junit4] 1> 12149 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 12148 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 12153 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@69f199b main{StandardDirectoryReader(segments_k:43:nrt _c(5.0):c2)}
[junit4:junit4] 1> 12154 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 12
[junit4:junit4] 1> 12158 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=2 status=0 QTime=1
[junit4:junit4] 1> 12160 [TEST-SolrExampleJettyTest.testUpdateRequestWithParameters-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateRequestWithParameters
[junit4:junit4] 1> 12174 [TEST-SolrExampleJettyTest.testCommitWithinOnAdd-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testCommitWithinOnAdd
[junit4:junit4] 1> 12179 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2c67e43d realtime
[junit4:junit4] 1> 12180 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164198024544256)} 0 2
[junit4:junit4] 1> 12184 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 12184 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_k,generation=20}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_l,generation=21}
[junit4:junit4] 1> 12185 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 21
[junit4:junit4] 1> 12186 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@42ad2ae main
[junit4:junit4] 1> 12187 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@42ad2ae main{StandardDirectoryReader(segments_k:45:nrt)}
[junit4:junit4] 1> 12187 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 12187 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 12191 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@42ad2ae main{StandardDirectoryReader(segments_k:45:nrt)}
[junit4:junit4] 1> 12193 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 10
[junit4:junit4] 1> 12197 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 1> 12203 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id3 (1438164198048661504)]} 0 2
[junit4:junit4] 1> 12207 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 12703 [commitScheduler-32-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> 12710 [commitScheduler-32-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5ecbd1ea main
[junit4:junit4] 1> 12710 [commitScheduler-32-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 12710 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5ecbd1ea main{StandardDirectoryReader(segments_l:47:nrt _d(5.0):c1)}
[junit4:junit4] 1> 12711 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 12715 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5ecbd1ea main{StandardDirectoryReader(segments_l:47:nrt _d(5.0):c1)}
[junit4:junit4] 1> 13212 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:id3&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 13218 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id4 (1438164199114014720)]} 0 2
[junit4:junit4] 1> 13718 [commitScheduler-32-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> 13725 [commitScheduler-32-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@609a86a6 main
[junit4:junit4] 1> 13726 [commitScheduler-32-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 13726 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@609a86a6 main{StandardDirectoryReader(segments_l:49:nrt _d(5.0):c1 _e(5.0):c1)}
[junit4:junit4] 1> 13727 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 13730 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@609a86a6 main{StandardDirectoryReader(segments_l:49:nrt _d(5.0):c1 _e(5.0):c1)}
[junit4:junit4] 1> 14224 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:id4&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 14227 [TEST-SolrExampleJettyTest.testCommitWithinOnAdd-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testCommitWithinOnAdd
[junit4:junit4] 1> 14240 [TEST-SolrExampleJettyTest.testPivotFacetsMissing-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPivotFacetsMissing
[junit4:junit4] 1> 14245 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5348caad realtime
[junit4:junit4] 1> 14246 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164200190902272)} 0 3
[junit4:junit4] 1> 14250 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 14251 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_l,generation=21}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_m,generation=22}
[junit4:junit4] 1> 14252 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 22
[junit4:junit4] 1> 14253 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4d07a915 main
[junit4:junit4] 1> 14254 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4d07a915 main{StandardDirectoryReader(segments_l:51:nrt)}
[junit4:junit4] 1> 14254 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 14254 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 14260 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4d07a915 main{StandardDirectoryReader(segments_l:51:nrt)}
[junit4:junit4] 1> 14262 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 12
[junit4:junit4] 1> 14266 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 14283 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 (1438164200220262400), 2 (1438164200221310976), 3 (1438164200222359552), 4 (1438164200223408128), 5 (1438164200224456704), 6 (1438164200225505280), 7 (1438164200226553856), 8 (1438164200227602432), 9 (1438164200228651008), 10 (1438164200229699584), ... (12 adds)]} 0 12
[junit4:junit4] 1> 14288 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 14293 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_m,generation=22}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_n,generation=23}
[junit4:junit4] 1> 14294 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 23
[junit4:junit4] 1> 14296 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2b90e289 main
[junit4:junit4] 1> 14297 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2b90e289 main{StandardDirectoryReader(segments_n:53:nrt _f(5.0):c12)}
[junit4:junit4] 1> 14297 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 14297 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 14303 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2b90e289 main{StandardDirectoryReader(segments_n:53:nrt _f(5.0):c12)}
[junit4:junit4] 1> 14305 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 17
[junit4:junit4] 1> 14317 [qtp645832403-54] 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> 14322 [qtp645832403-54] 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> 14325 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&facet=true&rows=0&facet.mincount=0&q=*:*&facet.missing=true&wt=javabin} hits=12 status=0 QTime=16
[junit4:junit4] 1> 14333 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&version=2&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&facet=true&rows=0&facet.mincount=0&q=*:*&wt=javabin} hits=11 status=0 QTime=2
[junit4:junit4] 1> 14335 [TEST-SolrExampleJettyTest.testPivotFacetsMissing-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPivotFacetsMissing
[junit4:junit4] 1> 14347 [TEST-SolrExampleJettyTest.testStreamingRequest-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testStreamingRequest
[junit4:junit4] 1> 14353 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@426a3f69 realtime
[junit4:junit4] 1> 14353 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164200303099904)} 0 2
[junit4:junit4] 1> 14357 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 14359 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_n,generation=23}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_o,generation=24}
[junit4:junit4] 1> 14360 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 24
[junit4:junit4] 1> 14361 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6f7a589a main
[junit4:junit4] 1> 14362 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@6f7a589a main{StandardDirectoryReader(segments_n:55:nrt)}
[junit4:junit4] 1> 14362 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 14363 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 14369 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@6f7a589a main{StandardDirectoryReader(segments_n:55:nrt)}
[junit4:junit4] 1> 14371 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 14
[junit4:junit4] 1> 14375 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 14388 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 14393 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_o,generation=24}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_p,generation=25}
[junit4:junit4] 1> 14393 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 25
[junit4:junit4] 1> 14395 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6873dc6c main
[junit4:junit4] 1> 14396 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@6873dc6c main{StandardDirectoryReader(segments_p:57:nrt _g(5.0):c10)}
[junit4:junit4] 1> 14397 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 14396 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 14403 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@6873dc6c main{StandardDirectoryReader(segments_p:57:nrt _g(5.0):c10)}
[junit4:junit4] 1> 14404 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {add=[0 (1438164200334557184), 1 (1438164200335605760), 2 (1438164200336654336), 3 (1438164200337702912), 4 (1438164200338751488), 5 (1438164200338751489), 6 (1438164200339800064), 7 (1438164200339800065), 8 (1438164200340848640), 9 (1438164200341897216)],commit=} 0 24
[junit4:junit4] 1> 14410 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&version=2&q=*:*&wt=javabin} hits=10 status=0 QTime=2
[junit4:junit4] 1> 14416 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&version=2&q=*:*&wt=javabin} hits=10 status=0 QTime=0
[junit4:junit4] 1> 14418 [TEST-SolrExampleJettyTest.testStreamingRequest-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testStreamingRequest
[junit4:junit4] 1> 14432 [TEST-SolrExampleJettyTest.testFaceting-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testFaceting
[junit4:junit4] 1> 14437 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2f8345f1 realtime
[junit4:junit4] 1> 14437 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164200391180288)} 0 2
[junit4:junit4] 1> 14441 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 14442 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_p,generation=25}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_q,generation=26}
[junit4:junit4] 1> 14443 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 26
[junit4:junit4] 1> 14443 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@33c95a7b main
[junit4:junit4] 1> 14444 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@33c95a7b main{StandardDirectoryReader(segments_p:59:nrt)}
[junit4:junit4] 1> 14444 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 14445 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 14449 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@33c95a7b main{StandardDirectoryReader(segments_p:59:nrt)}
[junit4:junit4] 1> 14450 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 9
[junit4:junit4] 1> 14454 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 14466 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 (1438164200416346112), 2 (1438164200418443264), 3 (1438164200418443265), 4 (1438164200419491840), 5 (1438164200420540416), 6 (1438164200420540417), 7 (1438164200421588992), 8 (1438164200421588993), 9 (1438164200422637568), 10 (1438164200423686144)]} 0 7
[junit4:junit4] 1> 14471 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 14476 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_q,generation=26}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_r,generation=27}
[junit4:junit4] 1> 14476 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 27
[junit4:junit4] 1> 14478 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@111fea54 main
[junit4:junit4] 1> 14480 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@111fea54 main{StandardDirectoryReader(segments_r:61:nrt _h(5.0):c10)}
[junit4:junit4] 1> 14480 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 14480 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 14484 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@111fea54 main{StandardDirectoryReader(segments_r:61:nrt _h(5.0):c10)}
[junit4:junit4] 1> 14485 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 14
[junit4:junit4] 1> 14490 [qtp645832403-53] 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> 14491 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet.field=features&version=2&facet=true&rows=0&facet.mincount=0&q=*:*&wt=javabin} hits=10 status=0 QTime=2
[junit4:junit4] 1> 14497 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fq=features:two&facet.field=features&version=2&facet=true&rows=0&facet.mincount=0&q=*:*&wt=javabin} hits=5 status=0 QTime=1
[junit4:junit4] 1> 14502 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fq=features:two&facet.field=features&version=2&facet=true&rows=0&facet.mincount=4&q=*:*&wt=javabin} hits=5 status=0 QTime=1
[junit4:junit4] 1> 14507 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fq=features:two&facet.field=features&version=2&facet=true&rows=0&facet.mincount=-1&q=*:*&wt=javabin} hits=5 status=0 QTime=1
[junit4:junit4] 1> 14510 [TEST-SolrExampleJettyTest.testFaceting-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testFaceting
[junit4:junit4] 1> 14523 [TEST-SolrExampleJettyTest.testAddRetrieve-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAddRetrieve
[junit4:junit4] 1> 14527 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@75ef56ec realtime
[junit4:junit4] 1> 14528 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164200486600704)} 0 2
[junit4:junit4] 1> 14536 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id1 (1438164200493940736), id2 (1438164200496037888)]} 0 4
[junit4:junit4] 1> 14541 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 14546 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_r,generation=27}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_s,generation=28}
[junit4:junit4] 1> 14547 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 28
[junit4:junit4] 1> 14549 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4f148b07 main
[junit4:junit4] 1> 14550 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4f148b07 main{StandardDirectoryReader(segments_s:65:nrt _i(5.0):c2)}
[junit4:junit4] 1> 14550 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 14550 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 14554 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4f148b07 main{StandardDirectoryReader(segments_s:65:nrt _i(5.0):c2)}
[junit4:junit4] 1> 14556 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 15
[junit4:junit4] 1> 14605 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&version=2&q=*:*&wt=javabin} hits=2 status=0 QTime=44
[junit4:junit4] 1> 14616 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id1 (1438164200575729664), id2 (1438164200578875392)]} 0 5
[junit4:junit4] 1> 29614 [commitScheduler-31-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> 29622 [commitScheduler-31-thread-1] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_s,generation=28}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_t,generation=29}
[junit4:junit4] 1> 29622 [commitScheduler-31-thread-1] INFO org.apache.solr.core.SolrCore ? newest commit generation = 29
[junit4:junit4] 1> 29625 [commitScheduler-31-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@e8b7cf1 realtime
[junit4:junit4] 1> 29626 [commitScheduler-31-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 214874 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 214874 [TEST-SolrExampleJettyTest.testAddRetrieve-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAddRetrieve
[junit4:junit4] 1> 214876 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_t,generation=29}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_u,generation=30}
[junit4:junit4] 1> 214877 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 30
[junit4:junit4] 1> 214878 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5bf58e9f main
[junit4:junit4] 1> 214880 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 214880 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5bf58e9f main{StandardDirectoryReader(segments_t:69:nrt _j(5.0):c2)}
[junit4:junit4] 1> 214881 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 214890 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5bf58e9f main{StandardDirectoryReader(segments_t:69:nrt _j(5.0):c2)}
[junit4:junit4] 1> 214894 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 20
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleJettyTest -Dtests.method=testAddRetrieve -Dtests.seed=BA1B2474D2DE77FB -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=hr -Dtests.timezone=Asia/Khandyga -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR 200s J1 | SolrExampleJettyTest.testAddRetrieve <<<
[junit4:junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:41458/solr/collection1
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([BA1B2474D2DE77FB:59C8B5D3E9615D42]:0)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:436)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4:junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:168)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrExampleTests.testAddRetrieve(SolrExampleTests.java:255)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4:junit4] > Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
[junit4:junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
[junit4:junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
[junit4:junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
[junit4:junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
[junit4:junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
[junit4:junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
[junit4:junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
[junit4:junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
[junit4:junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
[junit4:junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
[junit4:junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4:junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4:junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
[junit4:junit4] > ... 45 more
[junit4:junit4] 1> 214941 [TEST-SolrExampleJettyTest.testPivotFacets-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPivotFacets
[junit4:junit4] 1> 214956 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@9ab6351 realtime
[junit4:junit4] 1> 214957 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164410650591232)} 0 3
[junit4:junit4] 1> 214963 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 214964 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_u,generation=30}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_v,generation=31}
[junit4:junit4] 1> 214965 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 31
[junit4:junit4] 1> 214966 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2cc5f53a main
[junit4:junit4] 1> 214968 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2cc5f53a main{StandardDirectoryReader(segments_u:71:nrt)}
[junit4:junit4] 1> 214968 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 214968 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 214975 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2cc5f53a main{StandardDirectoryReader(segments_u:71:nrt)}
[junit4:junit4] 1> 214977 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 14
[junit4:junit4] 1> 214982 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 1> 215004 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 (1438164410689388544), 2 (1438164410691485696), 3 (1438164410692534272), 4 (1438164410693582848), 5 (1438164410694631424), 6 (1438164410695680000), 7 (1438164410696728576), 8 (1438164410697777152), 9 (1438164410698825728), 10 (1438164410699874304), ... (12 adds)]} 0 14
[junit4:junit4] 1> 215008 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215014 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_v,generation=31}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_w,generation=32}
[junit4:junit4] 1> 215015 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 32
[junit4:junit4] 1> 215017 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1a8080fb main
[junit4:junit4] 1> 215018 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1a8080fb main{StandardDirectoryReader(segments_w:73:nrt _k(5.0):c12)}
[junit4:junit4] 1> 215018 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215018 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215024 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1a8080fb main{StandardDirectoryReader(segments_w:73:nrt _k(5.0):c12)}
[junit4:junit4] 1> 215025 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 17
[junit4:junit4] 1> 215030 [qtp645832403-53] 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> 215031 [qtp645832403-53] 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> 215033 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&facet=true&rows=0&facet.mincount=0&q=*:*&facet.missing=false&wt=javabin} hits=12 status=0 QTime=4
[junit4:junit4] 1> 215040 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&version=2&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&facet=true&rows=0&facet.mincount=0&q=*:*&wt=javabin} hits=11 status=0 QTime=2
[junit4:junit4] 1> 215041 [TEST-SolrExampleJettyTest.testPivotFacets-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPivotFacets
[junit4:junit4] 1> 215056 [TEST-SolrExampleJettyTest.testBadSetup-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testBadSetup
[junit4:junit4] 1> 215057 [TEST-SolrExampleJettyTest.testBadSetup-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testBadSetup
[junit4:junit4] 1> 215067 [TEST-SolrExampleJettyTest.testPingHandler-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPingHandler
[junit4:junit4] 1> 215072 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@49c1330a realtime
[junit4:junit4] 1> 215072 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164410772226048)} 0 2
[junit4:junit4] 1> 215076 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215077 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_w,generation=32}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_x,generation=33}
[junit4:junit4] 1> 215077 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 33
[junit4:junit4] 1> 215078 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@464f52fc main
[junit4:junit4] 1> 215079 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@464f52fc main{StandardDirectoryReader(segments_w:75:nrt)}
[junit4:junit4] 1> 215079 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215080 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215086 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@464f52fc main{StandardDirectoryReader(segments_w:75:nrt)}
[junit4:junit4] 1> 215088 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 12
[junit4:junit4] 1> 215092 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 215101 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/ping params={version=2&wt=javabin} hits=0 status=0 QTime=3
[junit4:junit4] 1> 215103 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/ping params={version=2&wt=javabin} status=0 QTime=5
[junit4:junit4] 1> 215105 [TEST-SolrExampleJettyTest.testPingHandler-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPingHandler
[junit4:junit4] 1> 215122 [TEST-SolrExampleJettyTest.testRealtimeGet-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testRealtimeGet
[junit4:junit4] 1> 215128 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164410831994880)} 0 2
[junit4:junit4] 1> 215137 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[DOCID (1438164410840383488)]} 0 3
[junit4:junit4] 1> 215143 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215150 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_x,generation=33}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_y,generation=34}
[junit4:junit4] 1> 215152 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 34
[junit4:junit4] 1> 215155 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@19afe735 main
[junit4:junit4] 1> 215157 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@19afe735 main{StandardDirectoryReader(segments_y:77:nrt _l(5.0):c1)}
[junit4:junit4] 1> 215157 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215158 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215162 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@19afe735 main{StandardDirectoryReader(segments_y:77:nrt _l(5.0):c1)}
[junit4:junit4] 1> 215163 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 20
[junit4:junit4] 1> 215168 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/get params={qt=/get&fl=id,name,aaa:[value+v%3Daaa]&id=DOCID&version=2&wt=javabin} status=0 QTime=1
[junit4:junit4] 1> 215176 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/get params={qt=/get&fl=id,name,aaa:[value+v%3Daaa]&id=DOCID&version=2.2&wt=xml} status=0 QTime=0
[junit4:junit4] 1> 215195 [TEST-SolrExampleJettyTest.testRealtimeGet-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testRealtimeGet
[junit4:junit4] 1> 215205 [TEST-SolrExampleJettyTest.testAddDelete-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAddDelete
[junit4:junit4] 1> 215210 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7c31347a realtime
[junit4:junit4] 1> 215211 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164410917978112)} 0 2
[junit4:junit4] 1> 215216 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 & 222 (1438164410924269568)]} 0 2
[junit4:junit4] 1> 215219 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215222 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_y,generation=34}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_z,generation=35}
[junit4:junit4] 1> 215222 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 35
[junit4:junit4] 1> 215224 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5cdcd675 main
[junit4:junit4] 1> 215225 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5cdcd675 main{StandardDirectoryReader(segments_z:81:nrt _m(5.0):c1)}
[junit4:junit4] 1> 215225 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215226 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215231 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5cdcd675 main{StandardDirectoryReader(segments_z:81:nrt _m(5.0):c1)}
[junit4:junit4] 1> 215233 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 13
[junit4:junit4] 1> 215237 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 215242 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {delete=[0 & 222 (-1438164410951532544)]} 0 1
[junit4:junit4] 1> 215246 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215247 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_z,generation=35}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_10,generation=36}
[junit4:junit4] 1> 215247 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 36
[junit4:junit4] 1> 215248 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@65dc3edd main
[junit4:junit4] 1> 215249 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@65dc3edd main{StandardDirectoryReader(segments_10:83:nrt)}
[junit4:junit4] 1> 215250 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215249 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215254 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@65dc3edd main{StandardDirectoryReader(segments_10:83:nrt)}
[junit4:junit4] 1> 215256 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 10
[junit4:junit4] 1> 215260 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 215265 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 & 222 (1438164410975649792)]} 0 1
[junit4:junit4] 1> 215269 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215272 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_10,generation=36}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_11,generation=37}
[junit4:junit4] 1> 215273 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 37
[junit4:junit4] 1> 215274 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@325353e1 main
[junit4:junit4] 1> 215275 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@325353e1 main{StandardDirectoryReader(segments_11:85:nrt _n(5.0):c1)}
[junit4:junit4] 1> 215276 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215275 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215281 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@325353e1 main{StandardDirectoryReader(segments_11:85:nrt _n(5.0):c1)}
[junit4:junit4] 1> 215282 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 13
[junit4:junit4] 1> 215286 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 215293 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@15b50457 realtime
[junit4:junit4] 1> 215294 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=id:"0\ \&\ 222" (-1438164411005009920)} 0 3
[junit4:junit4] 1> 215298 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215299 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_11,generation=37}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_12,generation=38}
[junit4:junit4] 1> 215299 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 38
[junit4:junit4] 1> 215300 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@18d4811e main
[junit4:junit4] 1> 215301 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@18d4811e main{StandardDirectoryReader(segments_11:87:nrt)}
[junit4:junit4] 1> 215301 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215301 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215304 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@18d4811e main{StandardDirectoryReader(segments_11:87:nrt)}
[junit4:junit4] 1> 215306 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 8
[junit4:junit4] 1> 215309 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 215314 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 & 222 (1438164411027030016)]} 0 1
[junit4:junit4] 1> 215319 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 & 222 (1438164411032272896)]} 0 1
[junit4:junit4] 1> 215323 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[2 & 222 (1438164411037515776)]} 0 1
[junit4:junit4] 1> 215326 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215329 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_12,generation=38}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_13,generation=39}
[junit4:junit4] 1> 215330 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 39
[junit4:junit4] 1> 215332 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4e47c3d2 main
[junit4:junit4] 1> 215333 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4e47c3d2 main{StandardDirectoryReader(segments_13:89:nrt _o(5.0):c3)}
[junit4:junit4] 1> 215333 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215334 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215338 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4e47c3d2 main{StandardDirectoryReader(segments_13:89:nrt _o(5.0):c3)}
[junit4:junit4] 1> 215339 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 13
[junit4:junit4] 1> 215343 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=3 status=0 QTime=0
[junit4:junit4] 1> 215349 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {delete=[0 & 222 (-1438164411063730176), 1 & 222 (-1438164411064778752), 2 & 222 (-1438164411064778753)]} 0 1
[junit4:junit4] 1> 215353 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215355 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_13,generation=39}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_14,generation=40}
[junit4:junit4] 1> 215355 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 40
[junit4:junit4] 1> 215356 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@18daa1ad main
[junit4:junit4] 1> 215357 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@18daa1ad main{StandardDirectoryReader(segments_14:91:nrt)}
[junit4:junit4] 1> 215357 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215357 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215361 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@18daa1ad main{StandardDirectoryReader(segments_14:91:nrt)}
[junit4:junit4] 1> 215362 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 9
[junit4:junit4] 1> 215366 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 1> 215367 [TEST-SolrExampleJettyTest.testAddDelete-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAddDelete
[junit4:junit4] 1> 215378 [TEST-SolrExampleJettyTest.testMultiContentStreamRequest-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testMultiContentStreamRequest
[junit4:junit4] 1> 215382 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164411098333184)} 0 1
[junit4:junit4] 1> 215386 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215387 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_14,generation=40}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_15,generation=41}
[junit4:junit4] 1> 215387 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 41
[junit4:junit4] 1> 215388 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1fa6b8a5 main
[junit4:junit4] 1> 215389 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1fa6b8a5 main{StandardDirectoryReader(segments_14:91:nrt)}
[junit4:junit4] 1> 215390 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215390 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215394 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1fa6b8a5 main{StandardDirectoryReader(segments_14:91:nrt)}
[junit4:junit4] 1> 215395 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 9
[junit4:junit4] 1> 215399 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 1> 215517 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215539 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_15,generation=41}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_16,generation=42}
[junit4:junit4] 1> 215539 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 42
[junit4:junit4] 1> 215542 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5e28dd8b main
[junit4:junit4] 1> 215543 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5e28dd8b main{StandardDirectoryReader(segments_16:93:nrt _p(5.0):c5)}
[junit4:junit4] 1> 215543 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215544 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215547 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5e28dd8b main{StandardDirectoryReader(segments_16:93:nrt _p(5.0):c5)}
[junit4:junit4] 1> 215548 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={a=?&version=2&softCommit=false&echoParams=ALL&commit=true&wt=javabin&waitSearcher=true} {add=[SP2514N (1438164411202142208), 6H500F0 (1438164411212627968), TWINX2048-3200PRO (1438164411218919424), VS1GB400C3 (1438164411230453760), VDBDB1A16 (1438164411235696640)],commit=} 0 72
[junit4:junit4] 1> 215552 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=5 status=0 QTime=0
[junit4:junit4] 1> 215556 [TEST-SolrExampleJettyTest.testMultiContentStreamRequest-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testMultiContentStreamRequest
[junit4:junit4] 1> 215566 [TEST-SolrExampleJettyTest.testCommitWithinOnDelete-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testCommitWithinOnDelete
[junit4:junit4] 1> 215571 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4a37b3fb realtime
[junit4:junit4] 1> 215571 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164411295465472)} 0 2
[junit4:junit4] 1> 215575 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215576 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_16,generation=42}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_17,generation=43}
[junit4:junit4] 1> 215576 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 43
[junit4:junit4] 1> 215577 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@410e2f32 main
[junit4:junit4] 1> 215578 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@410e2f32 main{StandardDirectoryReader(segments_16:95:nrt)}
[junit4:junit4] 1> 215578 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215578 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215581 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@410e2f32 main{StandardDirectoryReader(segments_16:95:nrt)}
[junit4:junit4] 1> 215582 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 7
[junit4:junit4] 1> 215586 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=*:*&wt=javabin} hits=0 status=0 QTime=0
[junit4:junit4] 1> 215591 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id3 (1438164411317485568)]} 0 2
[junit4:junit4] 1> 215595 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 215599 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_17,generation=43}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_18,generation=44}
[junit4:junit4] 1> 215600 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 44
[junit4:junit4] 1> 215602 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@76e47de3 main
[junit4:junit4] 1> 215603 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@76e47de3 main{StandardDirectoryReader(segments_18:97:nrt _q(5.0):c1)}
[junit4:junit4] 1> 215603 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 215603 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 215607 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@76e47de3 main{StandardDirectoryReader(segments_18:97:nrt _q(5.0):c1)}
[junit4:junit4] 1> 215608 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 13
[junit4:junit4] 1> 215612 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:id3&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 215617 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {delete=[id3 (-1438164411344748544)]} 0 1
[junit4:junit4] 1> 215621 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:id3&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 215875 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:id3&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 216130 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:id3&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 216385 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:id3&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 216617 [commitScheduler-32-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> 216619 [commitScheduler-32-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@48165b05 main
[junit4:junit4] 1> 216620 [commitScheduler-32-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 216620 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@48165b05 main{StandardDirectoryReader(segments_18:99:nrt)}
[junit4:junit4] 1> 216621 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 216627 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@48165b05 main{StandardDirectoryReader(segments_18:99:nrt)}
[junit4:junit4] 1> 216641 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:id3&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 1> 216643 [TEST-SolrExampleJettyTest.testCommitWithinOnDelete-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testCommitWithinOnDelete
[junit4:junit4] 1> 216662 [TEST-SolrExampleJettyTest.testUpdateMultiValuedField-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateMultiValuedField
[junit4:junit4] 1> 216669 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[123 (1438164412446801920)]} 0 2
[junit4:junit4] 1> 216675 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 216680 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_18,generation=44}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_19,generation=45}
[junit4:junit4] 1> 216681 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 45
[junit4:junit4] 1> 216684 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5ca6d308 main
[junit4:junit4] 1> 216685 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5ca6d308 main{StandardDirectoryReader(segments_19:101:nrt _r(5.0):c1)}
[junit4:junit4] 1> 216686 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 216686 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 216692 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5ca6d308 main{StandardDirectoryReader(segments_19:101:nrt _r(5.0):c1)}
[junit4:junit4] 1> 216694 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 18
[junit4:junit4] 1> 216701 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:123&wt=javabin} hits=1 status=0 QTime=2
[junit4:junit4] 1> 216710 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[123 (1438164412489793536)]} 0 3
[junit4:junit4] 1> 216714 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 216717 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_19,generation=45}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1a,generation=46}
[junit4:junit4] 1> 216717 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 46
[junit4:junit4] 1> 216719 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4725af2 main
[junit4:junit4] 1> 216720 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4725af2 main{StandardDirectoryReader(segments_1a:105:nrt _s(5.0):c1)}
[junit4:junit4] 1> 216720 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 216720 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 216723 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4725af2 main{StandardDirectoryReader(segments_1a:105:nrt _s(5.0):c1)}
[junit4:junit4] 1> 216724 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 11
[junit4:junit4] 1> 216729 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:123&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 216735 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[123 (1438164412517056512)]} 0 2
[junit4:junit4] 1> 216739 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 216743 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1a,generation=46}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1b,generation=47}
[junit4:junit4] 1> 216744 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 47
[junit4:junit4] 1> 216746 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5de21f07 main
[junit4:junit4] 1> 216748 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5de21f07 main{StandardDirectoryReader(segments_1b:109:nrt _t(5.0):c1)}
[junit4:junit4] 1> 216748 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 216748 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 216752 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5de21f07 main{StandardDirectoryReader(segments_1b:109:nrt _t(5.0):c1)}
[junit4:junit4] 1> 216753 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 14
[junit4:junit4] 1> 216758 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:123&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 216759 [TEST-SolrExampleJettyTest.testUpdateMultiValuedField-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateMultiValuedField
[junit4:junit4] 1> 216770 [TEST-SolrExampleJettyTest.testUpdateField-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateField
[junit4:junit4] 1> 216774 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5f7b34b3 realtime
[junit4:junit4] 1> 216775 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164412557950976)} 0 2
[junit4:junit4] 1> 216778 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 216779 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1b,generation=47}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1c,generation=48}
[junit4:junit4] 1> 216779 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 48
[junit4:junit4] 1> 216780 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4e2a0f45 main
[junit4:junit4] 1> 216781 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4e2a0f45 main{StandardDirectoryReader(segments_1b:111:nrt)}
[junit4:junit4] 1> 216781 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 216781 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 216784 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4e2a0f45 main{StandardDirectoryReader(segments_1b:111:nrt)}
[junit4:junit4] 1> 216785 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 7
[junit4:junit4] 1> 216790 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[unique (1438164412574728192)]} 0 2
[junit4:junit4] 1> 216793 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 216797 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1c,generation=48}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1d,generation=49}
[junit4:junit4] 1> 216798 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 49
[junit4:junit4] 1> 216799 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@18c75c8a main
[junit4:junit4] 1> 216800 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@18c75c8a main{StandardDirectoryReader(segments_1d:113:nrt _u(5.0):c1)}
[junit4:junit4] 1> 216800 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 216801 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 216803 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@18c75c8a main{StandardDirectoryReader(segments_1d:113:nrt _u(5.0):c1)}
[junit4:junit4] 1> 216805 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 12
[junit4:junit4] 1> 216809 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&version=2&q=*:*&wt=javabin} hits=1 status=0 QTime=1
[junit4:junit4] 1> 216813 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {} 0 1
[junit4:junit4] 1> 216818 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[unique (1438164412604088320)]} 0 1
[junit4:junit4] 1> 216822 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 216826 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1d,generation=49}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1e,generation=50}
[junit4:junit4] 1> 216826 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 50
[junit4:junit4] 1> 216828 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@49426a3b main
[junit4:junit4] 1> 216829 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@49426a3b main{StandardDirectoryReader(segments_1e:117:nrt _v(5.0):c1)}
[junit4:junit4] 1> 216829 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 216829 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 216832 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@49426a3b main{StandardDirectoryReader(segments_1e:117:nrt _v(5.0):c1)}
[junit4:junit4] 1> 216833 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 11
[junit4:junit4] 1> 216837 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&version=2&q=*:*&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 216843 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[unique (1438164412629254144)]} 0 2
[junit4:junit4] 1> 216846 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 216850 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1e,generation=50}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1f,generation=51}
[junit4:junit4] 1> 216850 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 51
[junit4:junit4] 1> 216852 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@32a06688 main
[junit4:junit4] 1> 216853 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@32a06688 main{StandardDirectoryReader(segments_1f:121:nrt _w(5.0):c1)}
[junit4:junit4] 1> 216854 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 216853 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 216857 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@32a06688 main{StandardDirectoryReader(segments_1f:121:nrt _w(5.0):c1)}
[junit4:junit4] 1> 216858 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 12
[junit4:junit4] 1> 216862 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&version=2&q=*:*&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 216864 [TEST-SolrExampleJettyTest.testUpdateField-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateField
[junit4:junit4] 1> 216873 [TEST-SolrExampleJettyTest.testSetNullUpdates-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testSetNullUpdates
[junit4:junit4] 1> 216878 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdates (1438164412665954304)]} 0 2
[junit4:junit4] 1> 216881 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 216885 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1f,generation=51}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1g,generation=52}
[junit4:junit4] 1> 216886 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generation = 52
[junit4:junit4] 1> 216888 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6a93df1 main
[junit4:junit4] 1> 216889 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@6a93df1 main{StandardDirectoryReader(segments_1g:123:nrt _w(5.0):c1 _x(5.0):c1)}
[junit4:junit4] 1> 216890 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 216890 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 216893 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@6a93df1 main{StandardDirectoryReader(segments_1g:123:nrt _w(5.0):c1 _x(5.0):c1)}
[junit4:junit4] 1> 216894 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 13
[junit4:junit4] 1> 216900 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdates (1438164412689022976)]} 0 3
[junit4:junit4] 1> 216905 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 216909 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1g,generation=52}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1h,generation=53}
[junit4:junit4] 1> 216910 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 53
[junit4:junit4] 1> 216911 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1029c633 main
[junit4:junit4] 1> 216912 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1029c633 main{StandardDirectoryReader(segments_1h:127:nrt _w(5.0):c1 _y(5.0):c1)}
[junit4:junit4] 1> 216912 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 216913 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 216915 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1029c633 main{StandardDirectoryReader(segments_1h:127:nrt _w(5.0):c1 _y(5.0):c1)}
[junit4:junit4] 1> 216916 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 11
[junit4:junit4] 1> 216920 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&q=id:testSetNullUpdates&wt=javabin} hits=1 status=0 QTime=0
[junit4:junit4] 1> 216922 [TEST-SolrExampleJettyTest.testSetNullUpdates-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testSetNullUpdates
[junit4:junit4] 1> 216931 [TEST-SolrExampleJettyTest.testQueryWithParams-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testQueryWithParams
[junit4:junit4] 1> 216938 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={debug=true&version=2&q=query&wt=javabin} hits=0 status=0 QTime=4
[junit4:junit4] 1> 216940 [TEST-SolrExampleJettyTest.testQueryWithParams-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testQueryWithParams
[junit4:junit4] 1> 216949 [TEST-SolrExampleJettyTest.testErrorHandling-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testErrorHandling
[junit4:junit4] 1> 216975 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching ignore_exception
[junit4:junit4] 1> 216976 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/analysis/field params={qt=/analysis/field&analysis.fieldvalue=ignore_exception&version=2&wt=javabin&analysis.fieldtype=int} status=400 QTime=24
[junit4:junit4] 1> 216987 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {} 0 7
[junit4:junit4] 1> 216988 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching ignore_exception
[junit4:junit4] 1> 216992 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {} 0 0
[junit4:junit4] 1> 216993 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching uniqueKey
[junit4:junit4] 1> 216994 [TEST-SolrExampleJettyTest.testErrorHandling-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testErrorHandling
[junit4:junit4] 1> 217004 [TEST-SolrExampleJettyTest.testUnicode-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUnicode
[junit4:junit4] 1> 217008 [qtp645832403-56] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3dbfa951 realtime
[junit4:junit4] 1> 217009 [qtp645832403-56] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {deleteByQuery=*:* (-1438164412803317760)} 0 2
[junit4:junit4] 1> 217037 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {add=[0 (1438164412813803520), 1 (1438164412813803521), 2 (1438164412814852096), 3 (1438164412815900672), 4 (1438164412815900673), 5 (1438164412816949248), 6 (1438164412816949249), 7 (1438164412817997824), 8 (1438164412817997825), 9 (1438164412819046400), ... (29 adds)]} 0 21
[junit4:junit4] 1> 217041 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 217053 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1h,generation=53}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1i,generation=54}
[junit4:junit4] 1> 217053 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 54
[junit4:junit4] 1> 217055 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2f4c552c main
[junit4:junit4] 1> 217056 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2f4c552c main{StandardDirectoryReader(segments_1i:131:nrt _z(5.0):c29)}
[junit4:junit4] 1> 217056 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 217057 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 217064 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2f4c552c main{StandardDirectoryReader(segments_1i:131:nrt _z(5.0):c29)}
[junit4:junit4] 1> 217067 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2.2&softCommit=false&commit=true&wt=xml&waitSearcher=true} {commit=} 0 26
[junit4:junit4] 1> 217072 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2.2&rows=29&q=*:*&wt=xml} hits=29 status=0 QTime=0
[junit4:junit4] 1> 217095 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@22fab1e1 realtime
[junit4:junit4] 1> 217096 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {deleteByQuery=*:* (-1438164412893495296)} 0 3
[junit4:junit4] 1> 217120 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {add=[0 (1438164412903981056), 1 (1438164412906078208), 2 (1438164412906078209), 3 (1438164412907126784), 4 (1438164412908175360), 5 (1438164412909223936), 6 (1438164412909223937), 7 (1438164412910272512), 8 (1438164412911321088), 9 (1438164412912369664), ... (22 adds)]} 0 17
[junit4:junit4] 1> 217126 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 217134 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1i,generation=54}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1j,generation=55}
[junit4:junit4] 1> 217136 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 55
[junit4:junit4] 1> 217138 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@192ea9cd main
[junit4:junit4] 1> 217139 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@192ea9cd main{StandardDirectoryReader(segments_1j:135:nrt _10(5.0):c22)}
[junit4:junit4] 1> 217140 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 217139 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 217142 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@192ea9cd main{StandardDirectoryReader(segments_1j:135:nrt _10(5.0):c22)}
[junit4:junit4] 1> 217143 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2.2&softCommit=false&commit=true&wt=xml&waitSearcher=true} {commit=} 0 17
[junit4:junit4] 1> 217149 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2.2&rows=22&q=*:*&wt=xml} hits=22 status=0 QTime=1
[junit4:junit4] 1> 217163 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4f2a3f73 realtime
[junit4:junit4] 1> 217165 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1438164412965847040)} 0 4
[junit4:junit4] 1> 217172 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1438164412973187072), 1 (1438164412974235648), 2 (1438164412974235649), 3 (1438164412975284224)]} 0 3
[junit4:junit4] 1> 217176 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 217179 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1j,generation=55}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1k,generation=56}
[junit4:junit4] 1> 217179 [qtp645832403-53] INFO org.apache.solr.core.SolrCore ? newest commit generation = 56
[junit4:junit4] 1> 217181 [qtp645832403-53] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@72d995e1 main
[junit4:junit4] 1> 217182 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@72d995e1 main{StandardDirectoryReader(segments_1k:139:nrt _11(5.0):c4)}
[junit4:junit4] 1> 217182 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 217182 [qtp645832403-53] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 217185 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@72d995e1 main{StandardDirectoryReader(segments_1k:139:nrt _11(5.0):c4)}
[junit4:junit4] 1> 217186 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 10
[junit4:junit4] 1> 217189 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={version=2&rows=4&q=*:*&wt=javabin} hits=4 status=0 QTime=0
[junit4:junit4] 1> 217194 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7525e147 realtime
[junit4:junit4] 1> 217195 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {deleteByQuery=*:* (-1438164412998352896)} 0 2
[junit4:junit4] 1> 217219 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2.2&wt=xml} {add=[0 (1438164413007790080), 1 (1438164413008838656), 2 (1438164413008838657), 3 (1438164413009887232), 4 (1438164413009887233), 5 (1438164413010935808), 6 (1438164413010935809), 7 (1438164413011984384), 8 (1438164413011984385), 9 (1438164413013032960), ... (29 adds)]} 0 17
[junit4:junit4] 1> 217223 [qtp645832403-56] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 217230 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1k,generation=56}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1l,generation=57}
[junit4:junit4] 1> 217231 [qtp645832403-56] INFO org.apache.solr.core.SolrCore ? newest commit generati
[...truncated too long message...]
n1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[111 (1438164413403103232)]} 0 2
[junit4:junit4] 1> 217585 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 217589 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1s,generation=64}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1t,generation=65}
[junit4:junit4] 1> 217589 [qtp645832403-54] INFO org.apache.solr.core.SolrCore ? newest commit generation = 65
[junit4:junit4] 1> 217591 [qtp645832403-54] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@472b7db2 main
[junit4:junit4] 1> 217592 [qtp645832403-54] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 217592 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@472b7db2 main{StandardDirectoryReader(segments_1t:171:nrt _19(5.0):c1)}
[junit4:junit4] 1> 217593 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 217595 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@472b7db2 main{StandardDirectoryReader(segments_1t:171:nrt _19(5.0):c1)}
[junit4:junit4] 1> 217596 [qtp645832403-54] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 12
[junit4:junit4] 1> 217601 [qtp645832403-53] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[222 (1438164413425123328)]} 0 1
[junit4:junit4] 1> 217605 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 217609 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1t,generation=65}
[junit4:junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@79b8afa2 lockFactory=org.apache.lucene.store.NativeFSLockFactory@e125c11),segFN=segments_1u,generation=66}
[junit4:junit4] 1> 217610 [qtp645832403-55] INFO org.apache.solr.core.SolrCore ? newest commit generation = 66
[junit4:junit4] 1> 217611 [qtp645832403-55] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5327b72e main
[junit4:junit4] 1> 217612 [qtp645832403-55] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4:junit4] 1> 217612 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5327b72e main{StandardDirectoryReader(segments_1u:173:nrt _19(5.0):c1 _1a(5.0):c1)}
[junit4:junit4] 1> 217613 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4:junit4] 1> 217616 [searcherExecutor-29-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5327b72e main{StandardDirectoryReader(segments_1u:173:nrt _19(5.0):c1 _1a(5.0):c1)}
[junit4:junit4] 1> 217616 [qtp645832403-55] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={version=2&softCommit=false&commit=true&wt=javabin&waitSearcher=true} {commit=} 0 11
[junit4:junit4] 1> 217624 [qtp645832403-54] 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]&version=2&q=*:*&wt=javabin} hits=2 status=0 QTime=3
[junit4:junit4] 1> 217626 [TEST-SolrExampleJettyTest.testAugmentFields-seed#[BA1B2474D2DE77FB]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAugmentFields
[junit4:junit4] 1> 217691 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.CoreContainer ? Shutting down CoreContainer instance=1382721656
[junit4:junit4] 1> 217692 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCore ? [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3ac06ee8
[junit4:junit4] 1> 217715 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.update.UpdateHandler ? closing DirectUpdateHandler2{commits=66,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=264,cumulative_deletesById=5,cumulative_deletesByQuery=33,cumulative_errors=1}
[junit4:junit4] 1> 217716 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.update.SolrCoreState ? Closing SolrCoreState
[junit4:junit4] 1> 217716 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.update.DefaultSolrCoreState ? SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 1> 217717 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.update.DefaultSolrCoreState ? closing IndexWriter with IndexWriterCloser
[junit4:junit4] 1> 217718 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.SolrCore ? [collection1] Closing main searcher on request.
[junit4:junit4] 1> 217719 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 1> 217720 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153;done=false>>]
[junit4:junit4] 1> 217720 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153
[junit4:junit4] 1> 217720 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153/index;done=false>>]
[junit4:junit4] 1> 217721 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleJettyTest-1371540254153/index
[junit4:junit4] 1> 217721 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.eclipse.jetty.server.handler.ContextHandler ? stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 1> 217774 [SUITE-SolrExampleJettyTest-seed#[BA1B2474D2DE77FB]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=331), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=331)), sim=DefaultSimilarity, locale=hr, timezone=Asia/Khandyga
[junit4:junit4] 2> NOTE: Linux 3.2.0-48-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=1,free=59141928,total=155189248
[junit4:junit4] 2> NOTE: All tests run in this JVM: [MultiCoreExampleJettyTest, SolrExampleJettyTest]
[junit4:junit4] Completed on J1 in 209.96s, 27 tests, 1 error <<< FAILURES!
[...truncated 37 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:386: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:366: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:194: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:437: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1243: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:887: There were test failures: 46 suites, 272 tests, 1 error
Total time: 38 minutes 22 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.8.0-ea-b93 -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure