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/07/09 23:37:07 UTC
[JENKINS] Lucene-Solr-4.x-Linux (64bit/jdk1.7.0_25) - Build # 6413
- Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/6413/
Java: 64bit/jdk1.7.0_25 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.client.solrj.SolrExampleBinaryTest.testStatistics
Error Message:
IOException occured when talking to server at: https://127.0.0.1:50253/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:50253/solr/collection1
at __randomizedtesting.SeedInfo.seed([6C0CE328E55D1F9A:9BA5D04C4970C572]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:861)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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 10834 lines...]
[junit4] Suite: org.apache.solr.client.solrj.SolrExampleBinaryTest
[junit4] 1> 0 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.SolrJettyTestBase ? Randomized ssl (true) and clientAuth (false)
[junit4] 1> 7 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ####initCore
[junit4] 1> 10 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ####initCore end
[junit4] 1> 201 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.eclipse.jetty.server.Server ? jetty-8.1.10.v20130312
[junit4] 1> 710 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-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] 1> 760 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.eclipse.jetty.server.AbstractConnector ? Started SslSelectChannelConnector@127.0.0.1:50253
[junit4] 1> 794 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? SolrDispatchFilter.init()
[junit4] 1> 883 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrResourceLoader ? JNDI not configured for solr (NoInitialContextEx)
[junit4] 1> 884 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrResourceLoader ? using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4] 1> 885 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrResourceLoader ? new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/'
[junit4] 1> 1173 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.ConfigSolr ? Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/solr.xml
[junit4] 1> 1591 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4] 1> 1596 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Discovered properties file /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/core.properties, adding to cores
[junit4] 1> 1600 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin
[junit4] 1> 1606 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn
[junit4] 1> 1621 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/prop-base
[junit4] 1> 1624 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/tmp
[junit4] 1> 1625 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/tmp/prop-base
[junit4] 1> 1626 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/tmp/text-base
[junit4] 1> 1627 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/tmp/props
[junit4] 1> 1628 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/text-base
[junit4] 1> 1629 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/bin/.svn/props
[junit4] 1> 1631 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn
[junit4] 1> 1636 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/prop-base
[junit4] 1> 1638 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/tmp
[junit4] 1> 1640 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/tmp/prop-base
[junit4] 1> 1641 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/tmp/text-base
[junit4] 1> 1643 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/tmp/props
[junit4] 1> 1645 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/text-base
[junit4] 1> 1647 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer ? Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/.svn/props
[junit4] 1> 1659 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.CoreContainer ? New CoreContainer 1390688494
[junit4] 1> 1664 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.CoreContainer ? Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/]
[junit4] 1> 1678 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting socketTimeout to: 0
[junit4] 1> 1680 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting urlScheme to: http://
[junit4] 1> 1682 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting connTimeout to: 0
[junit4] 1> 1683 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maxConnectionsPerHost to: 20
[junit4] 1> 1684 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting corePoolSize to: 0
[junit4] 1> 1685 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maximumPoolSize to: 2147483647
[junit4] 1> 1687 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting maxThreadIdleTime to: 5
[junit4] 1> 1688 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting sizeOfQueue to: -1
[junit4] 1> 1689 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory ? Setting fairnessPolicy to: false
[junit4] 1> 1711 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-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] 1> 1842 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.logging.LogWatcher ? Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 1> 1886 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.CoreContainer ? Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1
[junit4] 1> 1888 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrResourceLoader ? new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/'
[junit4] 1> 2007 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrConfig ? Adding specified lib dirs to ClassLoader
[junit4] 1> 2036 [coreLoadExecutor-3-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/extraction/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/extraction/lib).
[junit4] 1> 2037 [coreLoadExecutor-3-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 1> 2056 [coreLoadExecutor-3-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/clustering/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/clustering/lib).
[junit4] 1> 2057 [coreLoadExecutor-3-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 1> 2076 [coreLoadExecutor-3-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/langid/lib/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/langid/lib).
[junit4] 1> 2077 [coreLoadExecutor-3-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 1> 2096 [coreLoadExecutor-3-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? No files added to classloader from lib: ../../../contrib/velocity/lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../contrib/velocity/lib).
[junit4] 1> 2097 [coreLoadExecutor-3-thread-1] WARN org.apache.solr.core.SolrResourceLoader ? Can't find (or read) directory to add to classloader: ../../../dist/ (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/../../../dist).
[junit4] 1> 2303 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.update.SolrIndexConfig ? IndexWriter infoStream solr logging is enabled
[junit4] 1> 2311 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrConfig ? Using Lucene MatchVersion: LUCENE_43
[junit4] 1> 2444 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.Config ? Loaded SolrConfig: solrconfig.xml
[junit4] 1> 2454 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.schema.IndexSchema ? Reading Solr Schema from schema.xml
[junit4] 1> 2547 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.schema.IndexSchema ? [collection1] Schema name=example
[junit4] 1> 3773 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.schema.IndexSchema ? unique key field: id
[junit4] 1> 4934 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider ? Reloading exchange rates from file currency.xml
[junit4] 1> 4945 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider ? Reloading exchange rates from file currency.xml
[junit4] 1> 5194 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? org.apache.solr.core.MockDirectoryFactory
[junit4] 1> 5204 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373405593983/
[junit4] 1> 5206 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.JmxMonitoredMap ? No JMX servers found, not exposing Solr information with JMX.
[junit4] 1> 5217 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 1> 5217 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4] 1> 5249 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory ? return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373405593983
[junit4] 1> 5250 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373405593983/index/
[junit4] 1> 5251 [coreLoadExecutor-3-thread-1] WARN org.apache.solr.core.SolrCore ? [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373405593983/index' doesn't exist. Creating new index...
[junit4] 1> 5258 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.CachingDirectoryFactory ? return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373405593983/index
[junit4] 1> 5778 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1,generation=1}
[junit4] 1> 5779 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? newest commit generation = 1
[junit4] 1> 5787 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? created json: solr.JSONResponseWriter
[junit4] 1> 5788 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4] 1> 5789 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? created velocity: solr.VelocityResponseWriter
[junit4] 1> 5797 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? created xslt: solr.XSLTResponseWriter
[junit4] 1> 5798 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.response.XSLTResponseWriter ? xsltCacheLifetimeSeconds=5
[junit4] 1> 6064 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /select: solr.SearchHandler
[junit4] 1> 6065 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /query: solr.SearchHandler
[junit4] 1> 6070 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /get: solr.RealTimeGetHandler
[junit4] 1> 6070 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /browse: solr.SearchHandler
[junit4] 1> 6077 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update: solr.UpdateRequestHandler
[junit4] 1> 6082 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/json: solr.JsonUpdateRequestHandler
[junit4] 1> 6086 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/csv: solr.CSVRequestHandler
[junit4] 1> 6086 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 1> 6088 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 1> 6088 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 1> 6089 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 1> 6089 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 1> 6090 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 1> 6095 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /admin/: solr.admin.AdminHandlers
[junit4] 1> 6100 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /admin/ping: solr.PingRequestHandler
[junit4] 1> 6105 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /debug/dump: solr.DumpRequestHandler
[junit4] 1> 6122 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /replication: solr.ReplicationHandler
[junit4] 1> 6123 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 6123 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /spell: solr.SearchHandler
[junit4] 1> 6124 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 6124 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /tvrh: solr.SearchHandler
[junit4] 1> 6125 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 6125 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /terms: solr.SearchHandler
[junit4] 1> 6126 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? adding lazy requestHandler: solr.SearchHandler
[junit4] 1> 6126 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.RequestHandlers ? created /elevate: solr.SearchHandler
[junit4] 1> 6155 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4] 1> 6162 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4] 1> 6163 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.loader.XMLLoader ? xsltCacheLifetimeSeconds=60
[junit4] 1> 6184 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@158198c7 main
[junit4] 1> 6212 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? Hard AutoCommit: if uncommited for 15000ms;
[junit4] 1> 6213 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.SolrCore ? Soft AutoCommit: disabled
[junit4] 1> 6213 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Initializing spell checkers
[junit4] 1> 6237 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.spelling.DirectSolrSpellChecker ? init: {name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 1> 6266 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? No queryConverter defined, using default converter
[junit4] 1> 6271 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.component.QueryElevationComponent ? Loading QueryElevation from: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/collection1/conf/elevate.xml
[junit4] 1> 6387 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.handler.ReplicationHandler ? Commits will be reserved for 10000
[junit4] 1> 6388 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@158198c7 main{StandardDirectoryReader(segments_1:1)}
[junit4] 1> 6389 [coreLoadExecutor-3-thread-1] INFO org.apache.solr.core.CoreContainer ? registering core: collection1
[junit4] 1> 6393 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1
[junit4] 1> 6394 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter ? SolrDispatchFilter.init() done
[junit4] 1> 6395 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.SolrJettyTestBase ? Jetty Assigned Port#50253
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/./solrtest-SolrExampleBinaryTest-1373405593983
[junit4] 1> 6428 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateRequestWithParameters
[junit4] 1> 6443 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 1> 6803 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=null path=null params={event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false} hits=0 status=0 QTime=409
[junit4] 1> 6805 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 6806 [searcherExecutor-4-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Loading spell index for spellchecker: default
[junit4] 1> 6806 [searcherExecutor-4-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent ? Loading spell index for spellchecker: wordbreak
[junit4] 1> 6807 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@158198c7 main{StandardDirectoryReader(segments_1:1)}
[junit4] 1> 6997 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onInit: commits: num=1
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1,generation=1}
[junit4] 1> 6999 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 1
[junit4] 1> 7015 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@206fb381 realtime
[junit4] 1> 7018 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120151374561280)} 0 98
[junit4] 1> 7111 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 7112 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? No uncommitted changes. Skipping IW.commit.
[junit4] 1> 7113 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3a713629 main
[junit4] 1> 7117 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3a713629 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 1> 7117 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 7117 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 7119 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3a713629 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 1> 7120 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 7197 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1440120151582179328)]} 0 69
[junit4] 1> 7204 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={overwrite=false&wt=javabin&version=2} {add=[id1 (1440120151659773952)]} 0 1
[junit4] 1> 7215 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 7506 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1,generation=1}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_2,generation=2}
[junit4] 1> 7507 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 2
[junit4] 1> 7687 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7e207abc main
[junit4] 1> 7688 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 7688 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@7e207abc main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):C2)}
[junit4] 1> 7689 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 7690 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@7e207abc main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):C2)}
[junit4] 1> 7691 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 476
[junit4] 1> 7796 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=96
[junit4] 1> 7838 [TEST-SolrExampleBinaryTest.testUpdateRequestWithParameters-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateRequestWithParameters
[junit4] 1> 7861 [TEST-SolrExampleBinaryTest.testFaceting-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testFaceting
[junit4] 1> 7861 [TEST-SolrExampleBinaryTest.testFaceting-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil ? Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 1> 8257 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3111cb50 realtime
[junit4] 1> 8258 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120152422088704)} 0 328
[junit4] 1> 8268 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 8269 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_2,generation=2}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_3,generation=3}
[junit4] 1> 8270 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 3
[junit4] 1> 8272 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7f08e353 main
[junit4] 1> 8273 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 8273 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@7f08e353 main{StandardDirectoryReader(segments_2:5:nrt)}
[junit4] 1> 8275 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 8275 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@7f08e353 main{StandardDirectoryReader(segments_2:5:nrt)}
[junit4] 1> 8277 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 8281 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 8311 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1440120152796430336), 2 (1440120152799576064), 3 (1440120152813207552), 4 (1440120152814256128), 5 (1440120152815304704), 6 (1440120152816353280), 7 (1440120152817401856), 8 (1440120152818450432), 9 (1440120152819499008), 10 (1440120152820547584)]} 0 24
[junit4] 1> 8321 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 8338 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_3,generation=3}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_4,generation=4}
[junit4] 1> 8339 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 4
[junit4] 1> 8356 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@13e95aa9 main
[junit4] 1> 8358 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@13e95aa9 main{StandardDirectoryReader(segments_4:7:nrt _1(4.5):C10)}
[junit4] 1> 8358 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 8359 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 8360 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@13e95aa9 main{StandardDirectoryReader(segments_4:7:nrt _1(4.5):C10)}
[junit4] 1> 8361 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 40
[junit4] 1> 8429 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=11,phase1=11,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4] 1> 8433 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=66
[junit4] 1> 8526 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=83
[junit4] 1> 8533 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=4&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1
[junit4] 1> 8540 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=-1&q=*:*&facet.field=features&wt=javabin&fq=features:two&version=2&rows=0} hits=5 status=0 QTime=1
[junit4] 1> 8542 [TEST-SolrExampleBinaryTest.testFaceting-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testFaceting
[junit4] 1> 8561 [TEST-SolrExampleBinaryTest.testQueryWithParams-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testQueryWithParams
[junit4] 1> 8579 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=query&debug=true&wt=javabin&version=2} hits=0 status=0 QTime=14
[junit4] 1> 8583 [TEST-SolrExampleBinaryTest.testQueryWithParams-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testQueryWithParams
[junit4] 1> 8609 [TEST-SolrExampleBinaryTest.testPivotFacetsMissing-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPivotFacetsMissing
[junit4] 1> 8615 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7fc1cb4f realtime
[junit4] 1> 8616 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120153138266112)} 0 3
[junit4] 1> 8626 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 8628 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_4,generation=4}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_5,generation=5}
[junit4] 1> 8629 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 5
[junit4] 1> 8630 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@637d425e main
[junit4] 1> 8632 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@637d425e main{StandardDirectoryReader(segments_4:9:nrt)}
[junit4] 1> 8632 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 8633 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@637d425e main{StandardDirectoryReader(segments_4:9:nrt)}
[junit4] 1> 8635 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 8636 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 8643 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 8663 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1440120153176014848), 2 (1440120153178112000), 3 (1440120153180209152), 4 (1440120153181257728), 5 (1440120153183354880), 6 (1440120153184403456), 7 (1440120153185452032), 8 (1440120153186500608), 9 (1440120153187549184), 10 (1440120153188597760), ... (12 adds)]} 0 15
[junit4] 1> 8672 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 8682 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_5,generation=5}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_6,generation=6}
[junit4] 1> 8683 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 6
[junit4] 1> 8686 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2f34ba23 main
[junit4] 1> 8688 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2f34ba23 main{StandardDirectoryReader(segments_6:11:nrt _2(4.5):C12)}
[junit4] 1> 8688 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 8688 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 8689 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2f34ba23 main{StandardDirectoryReader(segments_6:11:nrt _2(4.5):C12)}
[junit4] 1> 8690 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 18
[junit4] 1> 8697 [qtp576826801-17] 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] 1> 8717 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=1,phase1=1,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4] 1> 8722 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet.missing=true&facet=true&facet.mincount=0&q=*:*&wt=javabin&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2&rows=0} hits=12 status=0 QTime=27
[junit4] 1> 8738 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=9
[junit4] 1> 8741 [TEST-SolrExampleBinaryTest.testPivotFacetsMissing-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPivotFacetsMissing
[junit4] 1> 8764 [TEST-SolrExampleBinaryTest.testUnicode-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUnicode
[junit4] 1> 8782 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6579b984 realtime
[junit4] 1> 8784 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120153313378304)} 0 4
[junit4] 1> 8800 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440120153323864064), 1 (1440120153324912640), 2 (1440120153325961216), 3 (1440120153327009792), 4 (1440120153327009793), 5 (1440120153328058368), 6 (1440120153329106944), 7 (1440120153329106945), 8 (1440120153330155520), 9 (1440120153331204096), ... (11 adds)]} 0 10
[junit4] 1> 8808 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 8814 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_6,generation=6}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_7,generation=7}
[junit4] 1> 8815 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 7
[junit4] 1> 8817 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3b415cad main
[junit4] 1> 8819 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 8819 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3b415cad main{StandardDirectoryReader(segments_7:15:nrt _3(4.5):C11)}
[junit4] 1> 8820 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 8820 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3b415cad main{StandardDirectoryReader(segments_7:15:nrt _3(4.5):C11)}
[junit4] 1> 8822 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 1> 8827 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=11} hits=11 status=0 QTime=0
[junit4] 1> 8864 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6215723d realtime
[junit4] 1> 8865 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120153400410112)} 0 2
[junit4] 1> 8893 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440120153410895872), 1 (1440120153411944448), 2 (1440120153412993024), 3 (1440120153412993025), 4 (1440120153414041600), 5 (1440120153415090176), 6 (1440120153415090177), 7 (1440120153416138752), 8 (1440120153417187328), 9 (1440120153417187329), ... (29 adds)]} 0 20
[junit4] 1> 8902 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 8941 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_7,generation=7}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_8,generation=8}
[junit4] 1> 8942 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 8
[junit4] 1> 8944 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3fcc95b4 main
[junit4] 1> 8945 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 8945 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3fcc95b4 main{StandardDirectoryReader(segments_8:19:nrt _4(4.5):C29)}
[junit4] 1> 8946 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 8946 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3fcc95b4 main{StandardDirectoryReader(segments_8:19:nrt _4(4.5):C29)}
[junit4] 1> 8947 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 45
[junit4] 1> 8952 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=29} hits=29 status=0 QTime=0
[junit4] 1> 8972 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3e5928b8 realtime
[junit4] 1> 8974 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120153512607744)} 0 4
[junit4] 1> 8998 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440120153525190656), 1 (1440120153526239232), 2 (1440120153527287808), 3 (1440120153528336384), 4 (1440120153528336385), 5 (1440120153529384960), 6 (1440120153530433536), 7 (1440120153530433537), 8 (1440120153531482112), 9 (1440120153531482113), ... (24 adds)]} 0 16
[junit4] 1> 9006 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9016 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_8,generation=8}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_9,generation=9}
[junit4] 1> 9017 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 9
[junit4] 1> 9069 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1020c592 main
[junit4] 1> 9071 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1020c592 main{StandardDirectoryReader(segments_9:23:nrt _5(4.5):C24)}
[junit4] 1> 9072 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9072 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9072 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1020c592 main{StandardDirectoryReader(segments_9:23:nrt _5(4.5):C24)}
[junit4] 1> 9074 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 68
[junit4] 1> 9080 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=24} hits=24 status=0 QTime=1
[junit4] 1> 9092 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5d642c1f realtime
[junit4] 1> 9093 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120153638436864)} 0 3
[junit4] 1> 9109 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440120153647874048), 1 (1440120153648922624), 2 (1440120153649971200), 3 (1440120153649971201), 4 (1440120153651019776), 5 (1440120153651019777), 6 (1440120153652068352), 7 (1440120153652068353), 8 (1440120153653116928), 9 (1440120153654165504), ... (15 adds)]} 0 10
[junit4] 1> 9119 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9129 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_9,generation=9}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_a,generation=10}
[junit4] 1> 9130 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 10
[junit4] 1> 9131 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6cacff32 main
[junit4] 1> 9133 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@6cacff32 main{StandardDirectoryReader(segments_a:27:nrt _6(4.5):C15)}
[junit4] 1> 9133 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9133 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9134 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@6cacff32 main{StandardDirectoryReader(segments_a:27:nrt _6(4.5):C15)}
[junit4] 1> 9135 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 16
[junit4] 1> 9140 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=15} hits=15 status=0 QTime=1
[junit4] 1> 9152 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@63e2e4c4 realtime
[junit4] 1> 9153 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1440120153702400000)} 0 3
[junit4] 1> 9254 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1440120153803063296), 1 (1440120153804111872), 2 (1440120153804111873), 3 (1440120153805160448), 4 (1440120153806209024), 5 (1440120153807257600), 6 (1440120153807257601), 7 (1440120153808306176), 8 (1440120153808306177), 9 (1440120153809354752)]} 0 8
[junit4] 1> 9266 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9272 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_a,generation=10}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_b,generation=11}
[junit4] 1> 9273 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 11
[junit4] 1> 9275 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@66b98ed9 main
[junit4] 1> 9276 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@66b98ed9 main{StandardDirectoryReader(segments_b:31:nrt _7(4.5):C10)}
[junit4] 1> 9276 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9276 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9277 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@66b98ed9 main{StandardDirectoryReader(segments_b:31:nrt _7(4.5):C10)}
[junit4] 1> 9278 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 12
[junit4] 1> 9283 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=10} hits=10 status=0 QTime=0
[junit4] 1> 9293 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@65fad846 realtime
[junit4] 1> 9294 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120153850249216)} 0 2
[junit4] 1> 9306 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440120153858637824), 1 (1440120153859686400), 2 (1440120153860734976), 3 (1440120153861783552), 4 (1440120153861783553), 5 (1440120153862832128), 6 (1440120153863880704)]} 0 5
[junit4] 1> 9314 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9321 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_b,generation=11}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_c,generation=12}
[junit4] 1> 9323 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 12
[junit4] 1> 9325 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7280b7aa main
[junit4] 1> 9327 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@7280b7aa main{StandardDirectoryReader(segments_c:35:nrt _8(4.5):C7)}
[junit4] 1> 9327 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9328 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9330 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@7280b7aa main{StandardDirectoryReader(segments_c:35:nrt _8(4.5):C7)}
[junit4] 1> 9331 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 17
[junit4] 1> 9337 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=7} hits=7 status=0 QTime=0
[junit4] 1> 9344 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@56f8934b realtime
[junit4] 1> 9345 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120153903726592)} 0 2
[junit4] 1> 9377 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440120153915260928), 1 (1440120153916309504), 2 (1440120153917358080), 3 (1440120153918406656), 4 (1440120153919455232), 5 (1440120153919455233), 6 (1440120153920503808), 7 (1440120153921552384), 8 (1440120153922600960), 9 (1440120153923649536), ... (26 adds)]} 0 24
[junit4] 1> 9386 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9396 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_c,generation=12}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_d,generation=13}
[junit4] 1> 9397 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 13
[junit4] 1> 9400 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2ed1d9ea main
[junit4] 1> 9402 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9402 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2ed1d9ea main{StandardDirectoryReader(segments_d:39:nrt _9(4.5):C26)}
[junit4] 1> 9404 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9406 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2ed1d9ea main{StandardDirectoryReader(segments_d:39:nrt _9(4.5):C26)}
[junit4] 1> 9408 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 22
[junit4] 1> 9415 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=26} hits=26 status=0 QTime=1
[junit4] 1> 9433 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@40129a0d realtime
[junit4] 1> 9434 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120153994952704)} 0 4
[junit4] 1> 9453 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440120154007535616), 1 (1440120154008584192), 2 (1440120154009632768), 3 (1440120154010681344), 4 (1440120154011729920), 5 (1440120154011729921), 6 (1440120154012778496), 7 (1440120154013827072), 8 (1440120154014875648), 9 (1440120154015924224), ... (13 adds)]} 0 12
[junit4] 1> 9467 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9476 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_d,generation=13}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_e,generation=14}
[junit4] 1> 9477 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? newest commit generation = 14
[junit4] 1> 9479 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@64585aae main
[junit4] 1> 9481 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@64585aae main{StandardDirectoryReader(segments_e:43:nrt _a(4.5):C13)}
[junit4] 1> 9481 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9482 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9483 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@64585aae main{StandardDirectoryReader(segments_e:43:nrt _a(4.5):C13)}
[junit4] 1> 9484 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 17
[junit4] 1> 9490 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=13} hits=13 status=0 QTime=0
[junit4] 1> 9501 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@51cb001c realtime
[junit4] 1> 9503 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1440120154067304448)} 0 3
[junit4] 1> 9533 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1440120154081984512), 1 (1440120154083033088), 2 (1440120154084081664), 3 (1440120154085130240), 4 (1440120154086178816), 5 (1440120154087227392), 6 (1440120154087227393), 7 (1440120154088275968), 8 (1440120154089324544), 9 (1440120154090373120), ... (24 adds)]} 0 21
[junit4] 1> 9547 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9559 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_e,generation=14}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_f,generation=15}
[junit4] 1> 9560 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 15
[junit4] 1> 9562 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@28312cbb main
[junit4] 1> 9564 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@28312cbb main{StandardDirectoryReader(segments_f:47:nrt _b(4.5):C24)}
[junit4] 1> 9564 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9564 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9565 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@28312cbb main{StandardDirectoryReader(segments_f:47:nrt _b(4.5):C24)}
[junit4] 1> 9566 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 19
[junit4] 1> 9574 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=24} hits=24 status=0 QTime=1
[junit4] 1> 9600 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6127202b realtime
[junit4] 1> 9601 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {deleteByQuery=*:* (-1440120154171113472)} 0 3
[junit4] 1> 9613 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=xml&version=2.2} {add=[0 (1440120154180550656), 1 (1440120154181599232), 2 (1440120154182647808), 3 (1440120154183696384), 4 (1440120154184744960), 5 (1440120154185793536), 6 (1440120154185793537)]} 0 6
[junit4] 1> 9622 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9627 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_f,generation=15}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_g,generation=16}
[junit4] 1> 9628 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 16
[junit4] 1> 9630 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@46bc7325 main
[junit4] 1> 9631 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@46bc7325 main{StandardDirectoryReader(segments_g:51:nrt _c(4.5):C7)}
[junit4] 1> 9631 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9631 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9632 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@46bc7325 main{StandardDirectoryReader(segments_g:51:nrt _c(4.5):C7)}
[junit4] 1> 9633 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=xml&version=2.2&softCommit=false} {commit=} 0 11
[junit4] 1> 9639 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=xml&version=2.2&rows=7} hits=7 status=0 QTime=1
[junit4] 1> 9648 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5beb1172 realtime
[junit4] 1> 9649 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120154222493696)} 0 2
[junit4] 1> 9670 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 (1440120154231930880), 1 (1440120154234028032), 2 (1440120154234028033), 3 (1440120154235076608), 4 (1440120154236125184), 5 (1440120154237173760), 6 (1440120154238222336), 7 (1440120154238222337), 8 (1440120154239270912), 9 (1440120154240319488), ... (17 adds)]} 0 14
[junit4] 1> 9680 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9687 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_g,generation=16}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_h,generation=17}
[junit4] 1> 9688 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 17
[junit4] 1> 9740 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@25482b52 main
[junit4] 1> 9742 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@25482b52 main{StandardDirectoryReader(segments_h:55:nrt _d(4.5):C17)}
[junit4] 1> 9742 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9743 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9744 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@25482b52 main{StandardDirectoryReader(segments_h:55:nrt _d(4.5):C17)}
[junit4] 1> 9745 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 65
[junit4] 1> 9750 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2&rows=17} hits=17 status=0 QTime=0
[junit4] 1> 9755 [TEST-SolrExampleBinaryTest.testUnicode-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUnicode
[junit4] 1> 9773 [TEST-SolrExampleBinaryTest.testRealtimeGet-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testRealtimeGet
[junit4] 1> 9778 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@612ec15a realtime
[junit4] 1> 9779 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120154357760000)} 0 3
[junit4] 1> 9786 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[DOCID (1440120154366148608)]} 0 2
[junit4] 1> 9795 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9800 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_h,generation=17}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_i,generation=18}
[junit4] 1> 9801 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 18
[junit4] 1> 9804 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5c9b2ef3 main
[junit4] 1> 9806 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9806 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5c9b2ef3 main{StandardDirectoryReader(segments_i:59:nrt _e(4.5):C1)}
[junit4] 1> 9807 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9808 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5c9b2ef3 main{StandardDirectoryReader(segments_i:59:nrt _e(4.5):C1)}
[junit4] 1> 9809 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 1> 9816 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=javabin&qt=/get&version=2} status=0 QTime=1
[junit4] 1> 9821 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/get params={id=DOCID&fl=id,name,aaa:[value+v%3Daaa]&wt=xml&qt=/get&version=2.2} status=0 QTime=0
[junit4] 1> 9824 [TEST-SolrExampleBinaryTest.testRealtimeGet-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testRealtimeGet
[junit4] 1> 9846 [TEST-SolrExampleBinaryTest.testPivotFacets-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPivotFacets
[junit4] 1> 9852 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@444d9ce6 realtime
[junit4] 1> 9852 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120154435354624)} 0 2
[junit4] 1> 9860 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9861 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_i,generation=18}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_j,generation=19}
[junit4] 1> 9862 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 19
[junit4] 1> 9863 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2084d12 main
[junit4] 1> 9864 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9864 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2084d12 main{StandardDirectoryReader(segments_i:61:nrt)}
[junit4] 1> 9865 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9866 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2084d12 main{StandardDirectoryReader(segments_i:61:nrt)}
[junit4] 1> 9867 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 1> 9872 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 9893 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 (1440120154464714752), 2 (1440120154466811904), 3 (1440120154467860480), 4 (1440120154468909056), 5 (1440120154471006208), 6 (1440120154472054784), 7 (1440120154473103360), 8 (1440120154474151936), 9 (1440120154475200512), 10 (1440120154477297664), ... (12 adds)]} 0 15
[junit4] 1> 9901 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9906 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_j,generation=19}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_k,generation=20}
[junit4] 1> 9907 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 20
[junit4] 1> 9909 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@499fa9b0 main
[junit4] 1> 9911 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@499fa9b0 main{StandardDirectoryReader(segments_k:63:nrt _f(4.5):C12)}
[junit4] 1> 9911 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 9911 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 9913 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@499fa9b0 main{StandardDirectoryReader(segments_k:63:nrt _f(4.5):C12)}
[junit4] 1> 9913 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 9919 [qtp576826801-22] 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] 1> 9920 [qtp576826801-22] 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] 1> 9922 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet.missing=false&facet=true&facet.mincount=0&q=*:*&wt=javabin&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&version=2&rows=0} hits=12 status=0 QTime=4
[junit4] 1> 9931 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.mincount=0&q=*:*&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&version=2&rows=0} hits=11 status=0 QTime=2
[junit4] 1> 9934 [TEST-SolrExampleBinaryTest.testPivotFacets-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPivotFacets
[junit4] 1> 9961 [TEST-SolrExampleBinaryTest.testAugmentFields-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAugmentFields
[junit4] 1> 9968 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@58eac93b realtime
[junit4] 1> 9970 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120154556989440)} 0 4
[junit4] 1> 9981 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[111 (1440120154566426624)]} 0 6
[junit4] 1> 9988 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 9994 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_k,generation=20}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_l,generation=21}
[junit4] 1> 9995 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 21
[junit4] 1> 9998 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3524f5b7 main
[junit4] 1> 10000 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 10000 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3524f5b7 main{StandardDirectoryReader(segments_l:67:nrt _g(4.5):C1)}
[junit4] 1> 10001 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 10002 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3524f5b7 main{StandardDirectoryReader(segments_l:67:nrt _g(4.5):C1)}
[junit4] 1> 10003 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4] 1> 10012 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[222 (1440120154602078208)]} 0 3
[junit4] 1> 10021 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 10027 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_l,generation=21}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_m,generation=22}
[junit4] 1> 10028 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 22
[junit4] 1> 10037 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@779cfdb0 main
[junit4] 1> 10038 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@779cfdb0 main{StandardDirectoryReader(segments_m:69:nrt _g(4.5):C1 _h(4.5):C1)}
[junit4] 1> 10038 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 10039 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 10040 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@779cfdb0 main{StandardDirectoryReader(segments_m:69:nrt _g(4.5):C1 _h(4.5):C1)}
[junit4] 1> 10040 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 19
[junit4] 1> 10411 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&fl=id,price,[docid],[explain+style%3Dnl],score,aaa:[value+v%3Daaa],ten:[value+v%3D10+t%3Dint]&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=364
[junit4] 1> 10414 [TEST-SolrExampleBinaryTest.testAugmentFields-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAugmentFields
[junit4] 1> 10475 [TEST-SolrExampleBinaryTest.testAddRetrieve-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAddRetrieve
[junit4] 1> 10481 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6c746a2e realtime
[junit4] 1> 10482 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120155094908928)} 0 3
[junit4] 1> 10491 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1440120155102248960), id2 (1440120155105394688)]} 0 5
[junit4] 1> 10498 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 10503 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_m,generation=22}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_n,generation=23}
[junit4] 1> 10503 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 23
[junit4] 1> 10506 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6e8509c9 main
[junit4] 1> 10507 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 10507 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@6e8509c9 main{StandardDirectoryReader(segments_n:73:nrt _i(4.5):C2)}
[junit4] 1> 10508 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 10509 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@6e8509c9 main{StandardDirectoryReader(segments_n:73:nrt _i(4.5):C2)}
[junit4] 1> 10510 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 10515 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 1> 10523 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1440120155137900544), id2 (1440120155139997696)]} 0 4
[junit4] 1> 10531 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 10537 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_n,generation=23}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_o,generation=24}
[junit4] 1> 10538 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 24
[junit4] 1> 10541 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5ff09be3 main
[junit4] 1> 10542 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@5ff09be3 main{StandardDirectoryReader(segments_o:77:nrt _j(4.5):C2)}
[junit4] 1> 10543 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 10543 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 10544 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@5ff09be3 main{StandardDirectoryReader(segments_o:77:nrt _j(4.5):C2)}
[junit4] 1> 10544 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 1> 10550 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=1
[junit4] 1> 10556 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={sort=price+asc&q=name:h?llo&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 10558 [TEST-SolrExampleBinaryTest.testAddRetrieve-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAddRetrieve
[junit4] 1> 10579 [TEST-SolrExampleBinaryTest.testErrorHandling-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testErrorHandling
[junit4] 1> 10613 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching ignore_exception
[junit4] 1> 10614 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/analysis/field params={analysis.fieldtype=int&wt=javabin&qt=/analysis/field&analysis.fieldvalue=ignore_exception&version=2} status=400 QTime=30
[junit4] 1> 10710 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 89
[junit4] 1> 10711 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching ignore_exception
[junit4] 1> 10716 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 0
[junit4] 1> 10718 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? Ignoring exception matching uniqueKey
[junit4] 1> 10719 [TEST-SolrExampleBinaryTest.testErrorHandling-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testErrorHandling
[junit4] 1> 10740 [TEST-SolrExampleBinaryTest.testChineseDefaults-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testChineseDefaults
[junit4] 1> 10745 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5d068c37 realtime
[junit4] 1> 10746 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120155372781568)} 0 2
[junit4] 1> 10753 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 10754 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_o,generation=24}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_p,generation=25}
[junit4] 1> 10754 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 25
[junit4] 1> 10755 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@553320a8 main
[junit4] 1> 10756 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@553320a8 main{StandardDirectoryReader(segments_o:79:nrt)}
[junit4] 1> 10756 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 10757 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@553320a8 main{StandardDirectoryReader(segments_o:79:nrt)}
[junit4] 1> 10758 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 10758 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4] 1> 10763 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 10770 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 10773 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_p,generation=25}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_q,generation=26}
[junit4] 1> 10774 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 26
[junit4] 1> 10775 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@30e725df main
[junit4] 1> 10776 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@30e725df main{StandardDirectoryReader(segments_q:81:nrt _k(4.5):C1)}
[junit4] 1> 10776 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 10777 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 10777 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@30e725df main{StandardDirectoryReader(segments_q:81:nrt _k(4.5):C1)}
[junit4] 1> 10778 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[42 (1440120155398995968)],commit=} 0 10
[junit4] 1> 10784 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=????&wt=javabin&version=2} hits=1 status=0 QTime=2
[junit4] 1> 10786 [TEST-SolrExampleBinaryTest.testChineseDefaults-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testChineseDefaults
[junit4] 1> 10807 [TEST-SolrExampleBinaryTest.testStatistics-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testStatistics
[junit4] 1> 10812 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5a1d45f3 realtime
[junit4] 1> 10813 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120155441987584)} 0 3
[junit4] 1> 10820 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 10821 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_q,generation=26}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_r,generation=27}
[junit4] 1> 10822 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 27
[junit4] 1> 10823 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@54389785 main
[junit4] 1> 10824 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@54389785 main{StandardDirectoryReader(segments_q:83:nrt)}
[junit4] 1> 10824 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 10824 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 10825 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@54389785 main{StandardDirectoryReader(segments_q:83:nrt)}
[junit4] 1> 10826 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4] 1> 10830 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 10836 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1440120155467153408)]} 0 2
[junit4] 1> 10841 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1440120155473444864)]} 0 1
[junit4] 1> 10847 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1440120155479736320)]} 0 1
[junit4] 1> 10851 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1440120155484979200)]} 0 0
[junit4] 1> 10856 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1440120155490222080)]} 0 1
[junit4] 1> 10862 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc5 (1440120155495464960)]} 0 1
[junit4] 1> 10869 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc6 (1440120155502804992)]} 0 2
[junit4] 1> 10875 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc7 (1440120155509096448)]} 0 1
[junit4] 1> 10882 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc8 (1440120155516436480)]} 0 1
[junit4] 1> 10889 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc9 (1440120155522727936)]} 0 2
[junit4] 1> 10897 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 10905 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_r,generation=27}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_s,generation=28}
[junit4] 1> 10906 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 28
[junit4] 1> 10908 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3cfa173 main
[junit4] 1> 10910 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3cfa173 main{StandardDirectoryReader(segments_s:85:nrt _l(4.5):C10)}
[junit4] 1> 10910 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 10910 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 10912 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3cfa173 main{StandardDirectoryReader(segments_s:85:nrt _l(4.5):C10)}
[junit4] 1> 10912 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 15
[junit4] 1> 10918 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4] 1> 10977 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=10 status=0 QTime=51
[junit4] 1> 10986 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1044aa6 realtime
[junit4] 1> 10987 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120155625488384)} 0 2
[junit4] 1> 10996 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 10997 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_s,generation=28}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_t,generation=29}
[junit4] 1> 10998 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 29
[junit4] 1> 10999 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@42096939 main
[junit4] 1> 11000 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@42096939 main{StandardDirectoryReader(segments_s:87:nrt)}
[junit4] 1> 11001 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 11001 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 11002 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@42096939 main{StandardDirectoryReader(segments_s:87:nrt)}
[junit4] 1> 11003 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 1> 11008 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 11015 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc10 (1440120155654848512)]} 0 2
[junit4] 1> 11022 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc11 (1440120155662188544)]} 0 2
[junit4] 1> 11028 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc12 (1440120155669528576)]} 0 1
[junit4] 1> 11034 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc13 (1440120155675820032)]} 0 2
[junit4] 1> 11040 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc14 (1440120155682111488)]} 0 1
[junit4] 1> 11049 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 11056 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_t,generation=29}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_u,generation=30}
[junit4] 1> 11056 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 30
[junit4] 1> 11058 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4a5a1d04 main
[junit4] 1> 11059 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4a5a1d04 main{StandardDirectoryReader(segments_u:89:nrt _m(4.5):C5)}
[junit4] 1> 11060 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 11060 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4a5a1d04 main{StandardDirectoryReader(segments_u:89:nrt _m(4.5):C5)}
[junit4] 1> 11062 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 11063 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 1> 11068 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0
[junit4] 1> 11076 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={stats.field=val_pi&stats=true&q=*:*&wt=javabin&version=2&rows=0} hits=5 status=0 QTime=1
[junit4] 1> 11083 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@64b4273a realtime
[junit4] 1> 11084 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120155726151680)} 0 3
[junit4] 1> 11093 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 11096 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_u,generation=30}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_v,generation=31}
[junit4] 1> 11097 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 31
[junit4] 1> 11098 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@597399c main
[junit4] 1> 11100 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@597399c main{StandardDirectoryReader(segments_u:91:nrt)}
[junit4] 1> 11100 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 11101 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@597399c main{StandardDirectoryReader(segments_u:91:nrt)}
[junit4] 1> 11101 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 11103 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 11109 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 11117 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc0 (1440120155760754688)]} 0 3
[junit4] 1> 11123 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc1 (1440120155768094720)]} 0 2
[junit4] 1> 11131 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc2 (1440120155777531904)]} 0 1
[junit4] 1> 11138 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc3 (1440120155783823360)]} 0 2
[junit4] 1> 26117 [commitScheduler-6-thread-1] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 26121 [commitScheduler-6-thread-1] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_v,generation=31}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_w,generation=32}
[junit4] 1> 26121 [commitScheduler-6-thread-1] INFO org.apache.solr.core.SolrCore ? newest commit generation = 32
[junit4] 1> 26123 [commitScheduler-6-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@697125c realtime
[junit4] 1> 26123 [commitScheduler-6-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 211804 [TEST-SolrExampleBinaryTest.testStatistics-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testStatistics
[junit4] 1> 211805 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[doc4 (1440120366199472128)]} 0 1
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleBinaryTest -Dtests.method=testStatistics -Dtests.seed=6C0CE328E55D1F9A -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=zh -Dtests.timezone=Asia/Pontianak -Dtests.file.encoding=US-ASCII
[junit4] ERROR 201s J1 | SolrExampleBinaryTest.testStatistics <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:50253/solr/collection1
[junit4] > at __randomizedtesting.SeedInfo.seed([6C0CE328E55D1F9A:9BA5D04C4970C572]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:435)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4] > at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4] > at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:861)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] > Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
[junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
[junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
[junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
[junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
[junit4] > ... 45 more
[junit4] 1> 211902 [TEST-SolrExampleBinaryTest.testStreamingRequest-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testStreamingRequest
[junit4] 1> 211924 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@296e41ab realtime
[junit4] 1> 211925 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120366316912640)} 0 9
[junit4] 1> 211933 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 211934 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_w,generation=32}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_x,generation=33}
[junit4] 1> 211935 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? newest commit generation = 33
[junit4] 1> 211936 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@92341f1 main
[junit4] 1> 211937 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@92341f1 main{StandardDirectoryReader(segments_w:97:nrt)}
[junit4] 1> 211937 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 211937 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 211938 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@92341f1 main{StandardDirectoryReader(segments_w:97:nrt)}
[junit4] 1> 211939 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 1> 211944 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 1> 211954 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 211958 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_x,generation=33}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_y,generation=34}
[junit4] 1> 211959 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 34
[junit4] 1> 211960 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2e0f09b2 main
[junit4] 1> 211961 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2e0f09b2 main{StandardDirectoryReader(segments_y:99:nrt _p(4.5):C10)}
[junit4] 1> 211961 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 211961 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 211963 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2e0f09b2 main{StandardDirectoryReader(segments_y:99:nrt _p(4.5):C10)}
[junit4] 1> 211963 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0 (1440120366350467072), 1 (1440120366351515648), 2 (1440120366352564224), 3 (1440120366353612800), 4 (1440120366353612801), 5 (1440120366354661376), 6 (1440120366354661377), 7 (1440120366355709952), 8 (1440120366355709953), 9 (1440120366356758528)],commit=} 0 15
[junit4] 1> 211968 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=1
[junit4] 1> 211974 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,score,_docid_&q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 1> 211976 [TEST-SolrExampleBinaryTest.testStreamingRequest-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testStreamingRequest
[junit4] 1> 211990 [TEST-SolrExampleBinaryTest.testUpdateField-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateField
[junit4] 1> 211995 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@539b5b78 realtime
[junit4] 1> 211996 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120366398701568)} 0 3
[junit4] 1> 212004 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212006 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_y,generation=34}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_z,generation=35}
[junit4] 1> 212006 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 35
[junit4] 1> 212008 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1f748c4 main
[junit4] 1> 212009 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1f748c4 main{StandardDirectoryReader(segments_y:101:nrt)}
[junit4] 1> 212010 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212009 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212010 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1f748c4 main{StandardDirectoryReader(segments_y:101:nrt)}
[junit4] 1> 212012 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 212018 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1440120366421770240)]} 0 2
[junit4] 1> 212026 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212031 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_z,generation=35}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_10,generation=36}
[junit4] 1> 212032 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? newest commit generation = 36
[junit4] 1> 212035 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@78cbfbb6 main
[junit4] 1> 212036 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@78cbfbb6 main{StandardDirectoryReader(segments_10:103:nrt _q(4.5):C1)}
[junit4] 1> 212036 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212037 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212038 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@78cbfbb6 main{StandardDirectoryReader(segments_10:103:nrt _q(4.5):C1)}
[junit4] 1> 212039 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4] 1> 212045 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 212051 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {} 0 1
[junit4] 1> 212059 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1440120366464761856)]} 0 3
[junit4] 1> 212068 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212073 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_10,generation=36}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_11,generation=37}
[junit4] 1> 212074 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 37
[junit4] 1> 212076 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@20753fe6 main
[junit4] 1> 212077 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@20753fe6 main{StandardDirectoryReader(segments_11:107:nrt _r(4.5):C1)}
[junit4] 1> 212077 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212077 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212078 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@20753fe6 main{StandardDirectoryReader(segments_11:107:nrt _r(4.5):C1)}
[junit4] 1> 212078 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 212083 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 212089 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[unique (1440120366496219136)]} 0 2
[junit4] 1> 212095 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212101 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_11,generation=37}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_12,generation=38}
[junit4] 1> 212102 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 38
[junit4] 1> 212105 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7968afc5 main
[junit4] 1> 212106 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@7968afc5 main{StandardDirectoryReader(segments_12:111:nrt _s(4.5):C1)}
[junit4] 1> 212106 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212107 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212108 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@7968afc5 main{StandardDirectoryReader(segments_12:111:nrt _s(4.5):C1)}
[junit4] 1> 212109 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 14
[junit4] 1> 212114 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=id,price_f,name,_version_&q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 212115 [TEST-SolrExampleBinaryTest.testUpdateField-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateField
[junit4] 1> 212127 [TEST-SolrExampleBinaryTest.testGetEmptyResults-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testGetEmptyResults
[junit4] 1> 212133 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@5fdc5a50 realtime
[junit4] 1> 212134 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120366542356480)} 0 3
[junit4] 1> 212140 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212141 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_12,generation=38}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_13,generation=39}
[junit4] 1> 212141 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? newest commit generation = 39
[junit4] 1> 212142 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@34ea00c0 main
[junit4] 1> 212143 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@34ea00c0 main{StandardDirectoryReader(segments_12:113:nrt)}
[junit4] 1> 212143 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212143 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212144 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@34ea00c0 main{StandardDirectoryReader(segments_12:113:nrt)}
[junit4] 1> 212145 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 1> 212151 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id1 (1440120366561230848)]} 0 2
[junit4] 1> 212155 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id2 (1440120366566473728)]} 0 1
[junit4] 1> 212161 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212165 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_13,generation=39}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_14,generation=40}
[junit4] 1> 212165 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 40
[junit4] 1> 212168 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1722aaf4 main
[junit4] 1> 212169 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1722aaf4 main{StandardDirectoryReader(segments_14:115:nrt _t(4.5):C2)}
[junit4] 1> 212169 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212169 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212170 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1722aaf4 main{StandardDirectoryReader(segments_14:115:nrt _t(4.5):C2)}
[junit4] 1> 212171 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 212177 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={fl=foofoofoo&q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=2
[junit4] 1> 212178 [TEST-SolrExampleBinaryTest.testGetEmptyResults-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testGetEmptyResults
[junit4] 1> 212191 [TEST-SolrExampleBinaryTest.testMultiContentStreamRequest-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testMultiContentStreamRequest
[junit4] 1> 212196 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@7e2dfc9c realtime
[junit4] 1> 212197 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120366609465344)} 0 2
[junit4] 1> 212202 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212203 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_14,generation=40}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_15,generation=41}
[junit4] 1> 212204 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 41
[junit4] 1> 212204 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@78e1114 main
[junit4] 1> 212205 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@78e1114 main{StandardDirectoryReader(segments_14:117:nrt)}
[junit4] 1> 212206 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212206 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212207 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@78e1114 main{StandardDirectoryReader(segments_14:117:nrt)}
[junit4] 1> 212207 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4] 1> 212211 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 212252 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212266 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_15,generation=41}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_16,generation=42}
[junit4] 1> 212267 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 42
[junit4] 1> 212269 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2c92fcb9 main
[junit4] 1> 212270 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2c92fcb9 main{StandardDirectoryReader(segments_16:119:nrt _u(4.5):C5)}
[junit4] 1> 212271 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212271 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2c92fcb9 main{StandardDirectoryReader(segments_16:119:nrt _u(4.5):C5)}
[junit4] 1> 212270 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212272 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&echoParams=ALL&commit=true&a=?&wt=javabin&version=2&softCommit=false} {add=[SP2514N (1440120366643019776), 6H500F0 (1440120366650359808), TWINX2048-3200PRO (1440120366654554112), VS1GB400C3 (1440120366661894144), VDBDB1A16 (1440120366665039872)],commit=} 0 48
[junit4] 1> 212276 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0
[junit4] 1> 212280 [TEST-SolrExampleBinaryTest.testMultiContentStreamRequest-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testMultiContentStreamRequest
[junit4] 1> 212291 [TEST-SolrExampleBinaryTest.testLukeHandler-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testLukeHandler
[junit4] 1> 212296 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3e46a19 realtime
[junit4] 1> 212296 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120366713274368)} 0 2
[junit4] 1> 212301 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID0 (1440120366719565824)]} 0 1
[junit4] 1> 212305 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID1 (1440120366724808704)]} 0 0
[junit4] 1> 212309 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID2 (1440120366729003008)]} 0 0
[junit4] 1> 212313 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID3 (1440120366733197312)]} 0 0
[junit4] 1> 212317 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID4 (1440120366737391616)]} 0 0
[junit4] 1> 212324 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212328 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_16,generation=42}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_17,generation=43}
[junit4] 1> 212328 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 43
[junit4] 1> 212381 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@80e8a06 main
[junit4] 1> 212382 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@80e8a06 main{StandardDirectoryReader(segments_17:123:nrt _v(4.5):C5)}
[junit4] 1> 212382 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212383 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212384 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@80e8a06 main{StandardDirectoryReader(segments_17:123:nrt _v(4.5):C5)}
[junit4] 1> 212384 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 60
[junit4] 1> 212388 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0 QTime=0
[junit4] 1> 212397 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/luke params={wt=javabin&version=2} status=0 QTime=3
[junit4] 1> 212404 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/luke params={show=schema&wt=javabin&version=2} status=0 QTime=2
[junit4] 1> 212430 [TEST-SolrExampleBinaryTest.testLukeHandler-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testLukeHandler
[junit4] 1> 212441 [TEST-SolrExampleBinaryTest.testAddDelete-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testAddDelete
[junit4] 1> 212446 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@36874648 realtime
[junit4] 1> 212447 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120366870560768)} 0 3
[junit4] 1> 212452 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1440120366877900800)]} 0 1
[junit4] 1> 212459 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212463 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_17,generation=43}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_18,generation=44}
[junit4] 1> 212463 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? newest commit generation = 44
[junit4] 1> 212465 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@56427e08 main
[junit4] 1> 212466 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@56427e08 main{StandardDirectoryReader(segments_18:127:nrt _w(4.5):C1)}
[junit4] 1> 212467 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212467 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212468 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@56427e08 main{StandardDirectoryReader(segments_18:127:nrt _w(4.5):C1)}
[junit4] 1> 212469 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 212474 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 212479 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1440120366906212352)]} 0 1
[junit4] 1> 212486 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212487 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? No uncommitted changes. Skipping IW.commit.
[junit4] 1> 212488 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@43588c23 main
[junit4] 1> 212489 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@43588c23 main{StandardDirectoryReader(segments_18:129:nrt)}
[junit4] 1> 212490 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212490 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212491 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@43588c23 main{StandardDirectoryReader(segments_18:129:nrt)}
[junit4] 1> 212492 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 1> 212496 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 212502 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1440120366930329600)]} 0 2
[junit4] 1> 212509 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212512 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_18,generation=44}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_19,generation=45}
[junit4] 1> 212513 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 45
[junit4] 1> 212515 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6fa699e1 main
[junit4] 1> 212516 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@6fa699e1 main{StandardDirectoryReader(segments_19:131:nrt _x(4.5):C1)}
[junit4] 1> 212516 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212517 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212518 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@6fa699e1 main{StandardDirectoryReader(segments_19:131:nrt _x(4.5):C1)}
[junit4] 1> 212518 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 212523 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 212530 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@6a924c29 realtime
[junit4] 1> 212532 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=id:"0\ \&\ 222" (-1440120366959689728)} 0 2
[junit4] 1> 212539 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212540 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_19,generation=45}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1a,generation=46}
[junit4] 1> 212541 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 46
[junit4] 1> 212542 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2b70723d main
[junit4] 1> 212543 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2b70723d main{StandardDirectoryReader(segments_19:133:nrt)}
[junit4] 1> 212544 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212544 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212545 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2b70723d main{StandardDirectoryReader(segments_19:133:nrt)}
[junit4] 1> 212546 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 7
[junit4] 1> 212551 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 212557 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0 & 222 (1440120366988001280)]} 0 2
[junit4] 1> 212563 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1 & 222 (1440120366994292736)]} 0 1
[junit4] 1> 212567 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 & 222 (1440120366999535616)]} 0 1
[junit4] 1> 212573 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212577 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1a,generation=46}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1b,generation=47}
[junit4] 1> 212577 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 47
[junit4] 1> 212579 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@28ec249e main
[junit4] 1> 212580 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@28ec249e main{StandardDirectoryReader(segments_1b:135:nrt _y(4.5):C3)}
[junit4] 1> 212581 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212581 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212581 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@28ec249e main{StandardDirectoryReader(segments_1b:135:nrt _y(4.5):C3)}
[junit4] 1> 212583 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 212587 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0
[junit4] 1> 212593 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[0 & 222 (-1440120367025750016), 1 & 222 (-1440120367025750017), 2 & 222 (-1440120367025750018)]} 0 2
[junit4] 1> 212602 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212603 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? No uncommitted changes. Skipping IW.commit.
[junit4] 1> 212604 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@64a30643 main
[junit4] 1> 212605 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@64a30643 main{StandardDirectoryReader(segments_1b:137:nrt)}
[junit4] 1> 212606 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212605 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212606 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@64a30643 main{StandardDirectoryReader(segments_1b:137:nrt)}
[junit4] 1> 212608 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 1> 212612 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 212614 [TEST-SolrExampleBinaryTest.testAddDelete-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testAddDelete
[junit4] 1> 212630 [TEST-SolrExampleBinaryTest.testContentStreamRequest-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testContentStreamRequest
[junit4] 1> 212635 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120367069790208)} 0 1
[junit4] 1> 212647 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212648 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1b,generation=47}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1c,generation=48}
[junit4] 1> 212649 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 48
[junit4] 1> 212650 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1a2b37e2 main
[junit4] 1> 212651 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1a2b37e2 main{StandardDirectoryReader(segments_1b:137:nrt)}
[junit4] 1> 212651 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212651 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212652 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1a2b37e2 main{StandardDirectoryReader(segments_1b:137:nrt)}
[junit4] 1> 212653 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 1> 212658 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 212696 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212704 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1c,generation=48}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1d,generation=49}
[junit4] 1> 212705 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 49
[junit4] 1> 212707 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1665e34c main
[junit4] 1> 212708 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1665e34c main{StandardDirectoryReader(segments_1d:139:nrt _z(4.5):C10)}
[junit4] 1> 212709 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212709 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212710 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1665e34c main{StandardDirectoryReader(segments_1d:139:nrt _z(4.5):C10)}
[junit4] 1> 212711 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {add=[0553573403 (1440120367112781824), 0553579908 (1440120367115927552), 055357342X (1440120367118024704), 0553293354 (1440120367120121856), 0812521390 (1440120367122219008), 0812550706 (1440120367124316160), 0441385532 (1440120367125364736), 0380014300 (1440120367127461888), 0805080481 (1440120367129559040), 080508049X (1440120367132704768)],commit=} 0 46
[junit4] 1> 212715 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=10 status=0 QTime=0
[junit4] 1> 212719 [TEST-SolrExampleBinaryTest.testContentStreamRequest-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testContentStreamRequest
[junit4] 1> 212734 [TEST-SolrExampleBinaryTest.testSetNullUpdates-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testSetNullUpdates
[junit4] 1> 212739 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1440120367178842112)]} 0 1
[junit4] 1> 212746 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212750 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1d,generation=49}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1e,generation=50}
[junit4] 1> 212751 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? newest commit generation = 50
[junit4] 1> 212753 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@578e5065 main
[junit4] 1> 212755 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@578e5065 main{StandardDirectoryReader(segments_1e:141:nrt _z(4.5):C10 _10(4.5):C1)}
[junit4] 1> 212755 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212755 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212757 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@578e5065 main{StandardDirectoryReader(segments_1e:141:nrt _z(4.5):C10 _10(4.5):C1)}
[junit4] 1> 212757 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 11
[junit4] 1> 212799 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdates (1440120367240708096)]} 0 13
[junit4] 1> 212806 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212811 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1e,generation=50}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1f,generation=51}
[junit4] 1> 212812 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 51
[junit4] 1> 212815 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@491c5f62 main
[junit4] 1> 212816 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@491c5f62 main{StandardDirectoryReader(segments_1f:145:nrt _z(4.5):C10 _11(4.5):C1)}
[junit4] 1> 212816 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212817 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212818 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@491c5f62 main{StandardDirectoryReader(segments_1f:145:nrt _z(4.5):C10 _11(4.5):C1)}
[junit4] 1> 212819 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4] 1> 212824 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 212826 [TEST-SolrExampleBinaryTest.testSetNullUpdates-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testSetNullUpdates
[junit4] 1> 212841 [TEST-SolrExampleBinaryTest.testSetNullUpdateOrder-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testSetNullUpdateOrder
[junit4] 1> 212847 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1440120367291039744)]} 0 2
[junit4] 1> 212854 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212859 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1f,generation=51}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1g,generation=52}
[junit4] 1> 212860 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 52
[junit4] 1> 212862 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@3deef359 main
[junit4] 1> 212864 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@3deef359 main{StandardDirectoryReader(segments_1g:147:nrt _z(4.5):C10 _11(4.5):C1 _12(4.5):C1)}
[junit4] 1> 212864 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212865 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212866 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@3deef359 main{StandardDirectoryReader(segments_1g:147:nrt _z(4.5):C10 _11(4.5):C1 _12(4.5):C1)}
[junit4] 1> 212866 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 212872 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[testSetNullUpdateOrder (1440120367318302720)]} 0 2
[junit4] 1> 212878 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212884 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1g,generation=52}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1h,generation=53}
[junit4] 1> 212885 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 53
[junit4] 1> 212887 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@63ba4c7c main
[junit4] 1> 212889 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@63ba4c7c main{StandardDirectoryReader(segments_1h:151:nrt _z(4.5):C10 _11(4.5):C1 _13(4.5):C1)}
[junit4] 1> 212889 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212889 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212890 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@63ba4c7c main{StandardDirectoryReader(segments_1h:151:nrt _z(4.5):C10 _11(4.5):C1 _13(4.5):C1)}
[junit4] 1> 212891 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 13
[junit4] 1> 212896 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:testSetNullUpdateOrder&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 212898 [TEST-SolrExampleBinaryTest.testSetNullUpdateOrder-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testSetNullUpdateOrder
[junit4] 1> 212911 [TEST-SolrExampleBinaryTest.testCommitWithinOnAdd-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testCommitWithinOnAdd
[junit4] 1> 212916 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@23156b71 realtime
[junit4] 1> 212917 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120367363391488)} 0 3
[junit4] 1> 212922 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 212923 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1h,generation=53}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1i,generation=54}
[junit4] 1> 212924 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 54
[junit4] 1> 212925 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@638940d8 main
[junit4] 1> 212926 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@638940d8 main{StandardDirectoryReader(segments_1h:153:nrt)}
[junit4] 1> 212926 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 212926 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 212927 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@638940d8 main{StandardDirectoryReader(segments_1h:153:nrt)}
[junit4] 1> 212928 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 6
[junit4] 1> 212932 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 212939 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1440120367386460160)]} 0 3
[junit4] 1> 212943 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 213439 [commitScheduler-7-thread-1] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 1> 213494 [commitScheduler-7-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2ca72c6c main
[junit4] 1> 213495 [commitScheduler-7-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 213495 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2ca72c6c main{StandardDirectoryReader(segments_1i:155:nrt _14(4.5):C1)}
[junit4] 1> 213495 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 213496 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2ca72c6c main{StandardDirectoryReader(segments_1i:155:nrt _14(4.5):C1)}
[junit4] 1> 213948 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 213954 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id4 (1440120368451813376)]} 0 2
[junit4] 1> 214453 [commitScheduler-7-thread-1] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 1> 214458 [commitScheduler-7-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@480cd1a2 main
[junit4] 1> 214459 [commitScheduler-7-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 214459 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@480cd1a2 main{StandardDirectoryReader(segments_1i:157:nrt _14(4.5):C1 _15(4.5):C1)}
[junit4] 1> 214459 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 214460 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@480cd1a2 main{StandardDirectoryReader(segments_1i:157:nrt _14(4.5):C1 _15(4.5):C1)}
[junit4] 1> 214958 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id4&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 214960 [TEST-SolrExampleBinaryTest.testCommitWithinOnAdd-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testCommitWithinOnAdd
[junit4] 1> 214971 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testExampleConfig
[junit4] 1> 214977 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@28a8dfc1 realtime
[junit4] 1> 214978 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120369524506624)} 0 4
[junit4] 1> 214982 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1112211111 (1440120369530798080)]} 0 1
[junit4] 1> 214987 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 214991 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1i,generation=54}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1j,generation=55}
[junit4] 1> 214991 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 55
[junit4] 1> 214993 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@1c9d888d main
[junit4] 1> 214994 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@1c9d888d main{StandardDirectoryReader(segments_1j:161:nrt _16(4.5):C1)}
[junit4] 1> 214994 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 214994 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 214995 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@1c9d888d main{StandardDirectoryReader(segments_1j:161:nrt _16(4.5):C1)}
[junit4] 1> 214996 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 215001 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 215021 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1j,generation=55}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1k,generation=56}
[junit4] 1> 215022 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 56
[junit4] 1> 215023 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@4e8ab79b main
[junit4] 1> 215024 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 215024 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@4e8ab79b main{StandardDirectoryReader(segments_1k:163:nrt _17(4.5):C1)}
[junit4] 1> 215025 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 215025 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@4e8ab79b main{StandardDirectoryReader(segments_1k:163:nrt _17(4.5):C1)}
[junit4] 1> 215026 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 25
[junit4] 1> 215030 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:1112211111&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 215043 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2 (1440120369586372608), 3 (1440120369592664064), 4 (1440120369593712640), 5 (1440120369594761216)]} 0 10
[junit4] 1> 215049 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 215055 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1k,generation=56}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1l,generation=57}
[junit4] 1> 215056 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 57
[junit4] 1> 215058 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@454767b9 main
[junit4] 1> 215060 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@454767b9 main{StandardDirectoryReader(segments_1l:165:nrt _17(4.5):C1 _18(4.5):C4)}
[junit4] 1> 215060 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 215060 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 215061 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@454767b9 main{StandardDirectoryReader(segments_1l:165:nrt _17(4.5):C1 _18(4.5):C4)}
[junit4] 1> 215061 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 12
[junit4] 1> 215067 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 215073 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1l,generation=57}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1m,generation=58}
[junit4] 1> 215074 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 58
[junit4] 1> 215077 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2c2fb8d6 main
[junit4] 1> 215078 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 215078 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2c2fb8d6 main{StandardDirectoryReader(segments_1m:167:nrt _19(4.5):C5)}
[junit4] 1> 215079 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 215080 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2c2fb8d6 main{StandardDirectoryReader(segments_1m:167:nrt _19(4.5):C5)}
[junit4] 1> 215080 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={optimize=true&waitSearcher=true&maxSegments=1&wt=javabin&version=2} {optimize=} 0 13
[junit4] 1> 215106 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=javabin&version=2} hits=5 status=0 QTime=22
[junit4] 1> 215114 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&facet.query=price:[5+TO+*]&q=*:*&facet.field=inStock&facet.field=price&facet.field=timestamp_dt&wt=javabin&fq=inStock:true&version=2} hits=2 status=0 QTime=2
[junit4] 1> 215126 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={facet=true&facet.query=price:[*+TO+2]&facet.query=price:[2+TO+4]&q=foo&wt=javabin&fq={!field+f%3DinStock}true&fq={!term+f%3Dname}hoss&version=2} hits=0 status=0 QTime=7
[junit4] 1> 215127 [TEST-SolrExampleBinaryTest.testExampleConfig-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testExampleConfig
[junit4] 1> 215140 [TEST-SolrExampleBinaryTest.testCommitWithinOnDelete-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testCommitWithinOnDelete
[junit4] 1> 215144 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@769c50f7 realtime
[junit4] 1> 215144 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120369700667392)} 0 2
[junit4] 1> 215149 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 215150 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1m,generation=58}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1n,generation=59}
[junit4] 1> 215151 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? newest commit generation = 59
[junit4] 1> 215151 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@347a7586 main
[junit4] 1> 215152 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@347a7586 main{StandardDirectoryReader(segments_1m:169:nrt)}
[junit4] 1> 215152 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 215153 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@347a7586 main{StandardDirectoryReader(segments_1m:169:nrt)}
[junit4] 1> 215154 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 215154 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4] 1> 215158 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 215164 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[id3 (1440120369720590336)]} 0 2
[junit4] 1> 215170 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 215173 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1n,generation=59}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1o,generation=60}
[junit4] 1> 215174 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 60
[junit4] 1> 215226 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@26b8c907 main
[junit4] 1> 215227 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@26b8c907 main{StandardDirectoryReader(segments_1o:171:nrt _1a(4.5):C1)}
[junit4] 1> 215227 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 215228 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 215228 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@26b8c907 main{StandardDirectoryReader(segments_1o:171:nrt _1a(4.5):C1)}
[junit4] 1> 215229 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 59
[junit4] 1> 215233 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 215237 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {delete=[id3 (-1440120369799233536)]} 0 0
[junit4] 1> 215241 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 215496 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 215750 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 216005 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 216237 [commitScheduler-7-thread-1] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 1> 216239 [commitScheduler-7-thread-1] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@31f524a0 main
[junit4] 1> 216239 [commitScheduler-7-thread-1] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 216239 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@31f524a0 main{StandardDirectoryReader(segments_1o:173:nrt)}
[junit4] 1> 216240 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 216240 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@31f524a0 main{StandardDirectoryReader(segments_1o:173:nrt)}
[junit4] 1> 216259 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:id3&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 216260 [TEST-SolrExampleBinaryTest.testCommitWithinOnDelete-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testCommitWithinOnDelete
[junit4] 1> 216271 [TEST-SolrExampleBinaryTest.testUpdateMultiValuedField-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testUpdateMultiValuedField
[junit4] 1> 216276 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1440120370886606848)]} 0 2
[junit4] 1> 216297 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 216300 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1o,generation=60}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1p,generation=61}
[junit4] 1> 216301 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? newest commit generation = 61
[junit4] 1> 216303 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2567a34e main
[junit4] 1> 216304 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2567a34e main{StandardDirectoryReader(segments_1p:175:nrt _1b(4.5):C1)}
[junit4] 1> 216304 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 216304 [qtp576826801-22] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 216305 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2567a34e main{StandardDirectoryReader(segments_1p:175:nrt _1b(4.5):C1)}
[junit4] 1> 216306 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 1> 216310 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=1
[junit4] 1> 216315 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1440120370928549888)]} 0 2
[junit4] 1> 216321 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 216325 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1p,generation=61}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1q,generation=62}
[junit4] 1> 216326 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? newest commit generation = 62
[junit4] 1> 216328 [qtp576826801-17] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@71b978fe main
[junit4] 1> 216329 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@71b978fe main{StandardDirectoryReader(segments_1q:179:nrt _1c(4.5):C1)}
[junit4] 1> 216330 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 216330 [qtp576826801-17] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 216330 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@71b978fe main{StandardDirectoryReader(segments_1q:179:nrt _1c(4.5):C1)}
[junit4] 1> 216331 [qtp576826801-17] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 10
[junit4] 1> 216335 [qtp576826801-22] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 216341 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[123 (1440120370954764288)]} 0 2
[junit4] 1> 216346 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 216349 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1q,generation=62}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1r,generation=63}
[junit4] 1> 216350 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? newest commit generation = 63
[junit4] 1> 216351 [qtp576826801-16] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2d978886 main
[junit4] 1> 216352 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@2d978886 main{StandardDirectoryReader(segments_1r:183:nrt _1d(4.5):C1)}
[junit4] 1> 216353 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 216353 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@2d978886 main{StandardDirectoryReader(segments_1r:183:nrt _1d(4.5):C1)}
[junit4] 1> 216353 [qtp576826801-16] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 216354 [qtp576826801-16] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 1> 216358 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=id:123&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 1> 216360 [TEST-SolrExampleBinaryTest.testUpdateMultiValuedField-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testUpdateMultiValuedField
[junit4] 1> 216376 [TEST-SolrExampleBinaryTest.testPingHandler-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Starting testPingHandler
[junit4] 1> 216381 [qtp576826801-22] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@2502d82c realtime
[junit4] 1> 216381 [qtp576826801-22] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1440120370996707328)} 0 2
[junit4] 1> 216387 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 1> 216388 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1r,generation=63}
[junit4] 1> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7f81cc8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@da44a9a),segFN=segments_1s,generation=64}
[junit4] 1> 216388 [qtp576826801-19] INFO org.apache.solr.core.SolrCore ? newest commit generation = 64
[junit4] 1> 216389 [qtp576826801-19] INFO org.apache.solr.search.SolrIndexSearcher ? Opening Searcher@14af2ab6 main
[junit4] 1> 216390 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener sending requests to Searcher@14af2ab6 main{StandardDirectoryReader(segments_1r:185:nrt)}
[junit4] 1> 216390 [qtp576826801-19] INFO org.apache.solr.update.UpdateHandler ? end_commit_flush
[junit4] 1> 216391 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? QuerySenderListener done.
[junit4] 1> 216391 [searcherExecutor-4-thread-1] INFO org.apache.solr.core.SolrCore ? [collection1] Registered new searcher Searcher@14af2ab6 main{StandardDirectoryReader(segments_1r:185:nrt)}
[junit4] 1> 216392 [qtp576826801-19] INFO org.apache.solr.update.processor.LogUpdateProcessor ? [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 5
[junit4] 1> 216396 [qtp576826801-16] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=0
[junit4] 1> 216405 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} hits=0 status=0 QTime=4
[junit4] 1> 216406 [qtp576826801-17] INFO org.apache.solr.core.SolrCore ? [collection1] webapp=/solr path=/admin/ping params={wt=javabin&version=2} status=0 QTime=5
[junit4] 1> 216408 [TEST-SolrExampleBinaryTest.testPingHandler-seed#[6C0CE328E55D1F9A]] INFO org.apache.solr.SolrTestCaseJ4 ? ###Ending testPingHandler
[junit4] 1> 216469 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.CoreContainer ? Shutting down CoreContainer instance=1390688494
[junit4] 1> 216470 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCore ? [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@47813ee4
[junit4] 1> 216470 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.update.UpdateHandler ? closing DirectUpdateHandler2{commits=67,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=302,cumulative_deletesById=5,cumulative_deletesByQuery=35,cumulative_errors=1}
[junit4] 1> 216471 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.update.SolrCoreState ? Closing SolrCoreState
[junit4] 1> 216472 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.update.DefaultSolrCoreState ? SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 1> 216472 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.update.DefaultSolrCoreState ? closing IndexWriter with IndexWriterCloser
[junit4] 1> 216474 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.SolrCore ? [collection1] Closing main searcher on request.
[junit4] 1> 216475 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 1> 216476 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373405593983 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373405593983;done=false>>]
[junit4] 1> 216476 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373405593983
[junit4] 1> 216477 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373405593983/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373405593983/index;done=false>>]
[junit4] 1> 216478 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.core.CachingDirectoryFactory ? Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J1/solrtest-SolrExampleBinaryTest-1373405593983/index
[junit4] 1> 216479 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.eclipse.jetty.server.handler.ContextHandler ? stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 1> 216533 [SUITE-SolrExampleBinaryTest-seed#[6C0CE328E55D1F9A]-worker] INFO org.apache.solr.SolrTestCaseJ4 ? ###deleteCore
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=zh, timezone=Asia/Pontianak
[junit4] 2> NOTE: Linux 3.2.0-49-generic amd64/Oracle Corporation 1.7.0_25 (64-bit)/cpus=8,threads=1,free=107130592,total=131072000
[junit4] 2> NOTE: All tests run in this JVM: [SolrExampleBinaryTest]
[junit4] Completed on J1 in 220.12s, 26 tests, 1 error <<< FAILURES!
[...truncated 40 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:395: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:375: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build.xml:194: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/common-build.xml:449: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1250: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:893: There were test failures: 46 suites, 272 tests, 1 error
Total time: 44 minutes 26 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_25 -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure