You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/06/10 08:56:54 UTC
[JENKINS] Lucene-Solr-4.x-Linux (32bit/jdk1.8.0-ea-b92) - Build #
5999 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Linux/5999/
Java: 32bit/jdk1.8.0-ea-b92 -server -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest.testStatistics
Error Message:
IOException occured when talking to server at: https://127.0.0.1:33214/solr/collection1
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:33214/solr/collection1
at __randomizedtesting.SeedInfo.seed([3E85581A9914AB60:C92C6B7E35397188]:0)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:436)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.request(ConcurrentUpdateSolrServer.java:256)
at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:168)
at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:851)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:491)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
at java.lang.Thread.run(Thread.java:724)
Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
... 46 more
Build Log:
[...truncated 10683 lines...]
[junit4:junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleStreamingBinaryTest
[junit4:junit4] 1> 50484 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] WARN org.apache.solr.SolrTestCaseJ4 – startTrackingSearchers: numOpens=1 numCloses=1
[junit4:junit4] 1> 50485 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.SolrJettyTestBase – Randomized ssl (true) and clientAuth (false)
[junit4:junit4] 1> 50485 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ####initCore
[junit4:junit4] 1> 50486 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ####initCore end
[junit4:junit4] 1> 50495 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.eclipse.jetty.server.Server – jetty-8.1.10.v20130312
[junit4:junit4] 1> 50761 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.eclipse.jetty.util.ssl.SslContextFactory – Enabled Protocols [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2] of [SSLv2Hello, SSLv3, TLSv1, TLSv1.1, TLSv1.2]
[junit4:junit4] 1> 50786 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.eclipse.jetty.server.AbstractConnector – Started SslSelectChannelConnector@127.0.0.1:33214
[junit4:junit4] 1> 50788 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init()
[junit4:junit4] 1> 50788 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.SolrResourceLoader – JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 1> 50789 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50789 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.CoreContainer – looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/solr.xml
[junit4:junit4] 1> 50790 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.CoreContainer – New CoreContainer 10764013
[junit4:junit4] 1> 50790 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.CoreContainer – Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/'
[junit4:junit4] 1> 50791 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.SolrResourceLoader – new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr/'
[junit4:junit4] 1> 50853 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.SolrCoreDiscoverer – Looking for cores in /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/example/solr
[junit4:junit4] 1> 50853 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50854 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50854 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50855 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50855 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50856 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50856 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50857 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50858 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50858 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50859 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50859 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50860 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50861 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50861 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50862 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50862 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50863 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-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:junit4] 1> 50865 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting socketTimeout to: 0
[junit4:junit4] 1> 50865 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting urlScheme to: http://
[junit4:junit4] 1> 50865 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting connTimeout to: 0
[junit4:junit4] 1> 50866 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maxConnectionsPerHost to: 20
[junit4:junit4] 1> 50866 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting corePoolSize to: 0
[junit4:junit4] 1> 50866 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maximumPoolSize to: 2147483647
[junit4:junit4] 1> 50867 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting maxThreadIdleTime to: 5
[junit4:junit4] 1> 50867 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting sizeOfQueue to: -1
[junit4:junit4] 1> 50867 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.handler.component.HttpShardHandlerFactory – Setting fairnessPolicy to: false
[junit4:junit4] 1> 50868 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4] 1> 50870 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.logging.LogWatcher – Registering Log Listener
[junit4:junit4] 1> 50873 [coreLoadExecutor-112-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:junit4] 1> 50873 [coreLoadExecutor-112-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:junit4] 1> 50890 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrConfig – Adding specified lib dirs to ClassLoader
[junit4:junit4] 1> 50890 [coreLoadExecutor-112-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:junit4] 1> 50891 [coreLoadExecutor-112-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:junit4] 1> 50907 [coreLoadExecutor-112-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:junit4] 1> 50907 [coreLoadExecutor-112-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:junit4] 1> 50907 [coreLoadExecutor-112-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:junit4] 1> 50908 [coreLoadExecutor-112-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:junit4] 1> 50908 [coreLoadExecutor-112-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:junit4] 1> 50908 [coreLoadExecutor-112-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:junit4] 1> 50939 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrConfig – Using Lucene MatchVersion: LUCENE_43
[junit4:junit4] 1> 51005 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.Config – Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 1> 51006 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.schema.IndexSchema – Reading Solr Schema from schema.xml
[junit4:junit4] 1> 51022 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.schema.IndexSchema – [collection1] Schema name=example
[junit4:junit4] 1> 51744 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.schema.IndexSchema – unique key field: id
[junit4:junit4] 1> 51767 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider – Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 51774 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.schema.FileExchangeRateProvider – Reloading exchange rates from file currency.xml
[junit4:junit4] 1> 51809 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 51809 [coreLoadExecutor-112-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/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744/
[junit4:junit4] 1> 51810 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.JmxMonitoredMap – JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7c79dd
[junit4:junit4] 1> 51810 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Added SolrEventListener for newSearcher: org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4:junit4] 1> 51811 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Added SolrEventListener for firstSearcher: org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher warming in solrconfig.xml}]}
[junit4:junit4] 1> 51811 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 1> 51812 [coreLoadExecutor-112-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/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744
[junit4:junit4] 1> 51812 [coreLoadExecutor-112-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/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744/index/
[junit4:junit4] 1> 51813 [coreLoadExecutor-112-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/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744/index' doesn't exist. Creating new index...
[junit4:junit4] 1> 51814 [coreLoadExecutor-112-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/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744/index
[junit4:junit4] 1> 51816 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_1,generation=1}
[junit4:junit4] 1> 51817 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – newest commit generation = 1
[junit4:junit4] 1> 51818 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – created json: solr.JSONResponseWriter
[junit4:junit4] 1> 51818 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – adding lazy queryResponseWriter: solr.VelocityResponseWriter
[junit4:junit4] 1> 51818 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – created velocity: solr.VelocityResponseWriter
[junit4:junit4] 1> 51819 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – created xslt: solr.XSLTResponseWriter
[junit4:junit4] 1> 51819 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.response.XSLTResponseWriter – xsltCacheLifetimeSeconds=5
[junit4:junit4] 1> 51829 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /select: solr.SearchHandler
[junit4:junit4] 1> 51830 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /query: solr.SearchHandler
[junit4:junit4] 1> 51830 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /get: solr.RealTimeGetHandler
[junit4:junit4] 1> 51831 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /browse: solr.SearchHandler
[junit4:junit4] 1> 51832 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update: solr.UpdateRequestHandler
[junit4:junit4] 1> 51833 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/json: solr.JsonUpdateRequestHandler
[junit4:junit4] 1> 51833 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/csv: solr.CSVRequestHandler
[junit4:junit4] 1> 51834 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 51834 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4:junit4] 1> 51835 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 51835 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4:junit4] 1> 51836 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 51836 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4:junit4] 1> 51837 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /admin/: solr.admin.AdminHandlers
[junit4:junit4] 1> 51838 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /admin/ping: solr.PingRequestHandler
[junit4:junit4] 1> 51838 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 1> 51839 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /replication: solr.ReplicationHandler
[junit4:junit4] 1> 51839 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 51840 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /spell: solr.SearchHandler
[junit4:junit4] 1> 51840 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 51841 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /tvrh: solr.SearchHandler
[junit4:junit4] 1> 51841 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 51842 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /terms: solr.SearchHandler
[junit4:junit4] 1> 51842 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – adding lazy requestHandler: solr.SearchHandler
[junit4:junit4] 1> 51842 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.RequestHandlers – created /elevate: solr.SearchHandler
[junit4:junit4] 1> 51845 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 51847 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 51848 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.handler.loader.XMLLoader – xsltCacheLifetimeSeconds=60
[junit4:junit4] 1> 51854 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1552166 main
[junit4:junit4] 1> 51855 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.update.UpdateHandler – Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744/tlog
[junit4:junit4] 1> 51856 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – Hard AutoCommit: if uncommited for 15000ms;
[junit4:junit4] 1> 51856 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.SolrCore – Soft AutoCommit: disabled
[junit4:junit4] 1> 51857 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Initializing spell checkers
[junit4:junit4] 1> 51857 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.spelling.DirectSolrSpellChecker – init: {name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4:junit4] 1> 51858 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – No queryConverter defined, using default converter
[junit4:junit4] 1> 51858 [coreLoadExecutor-112-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:junit4] 1> 51871 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.handler.ReplicationHandler – Commits will be reserved for 10000
[junit4:junit4] 1> 51877 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1552166 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 51881 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] webapp=null path=null params={distrib=false&event=firstSearcher&q=static+firstSearcher+warming+in+solrconfig.xml} hits=0 status=0 QTime=1
[junit4:junit4] 1> 51882 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 51882 [searcherExecutor-113-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Loading spell index for spellchecker: default
[junit4:junit4] 1> 51882 [searcherExecutor-113-thread-1] INFO org.apache.solr.handler.component.SpellCheckComponent – Loading spell index for spellchecker: wordbreak
[junit4:junit4] 1> 51884 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1552166 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 1> 51887 [coreLoadExecutor-112-thread-1] INFO org.apache.solr.core.CoreContainer – registering core: collection1
[junit4:junit4] 1> 51888 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0
[junit4:junit4] 1> 51888 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.servlet.SolrDispatchFilter – SolrDispatchFilter.init() done
[junit4:junit4] 1> 51889 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.SolrJettyTestBase – Jetty Assigned Port#33214
[junit4:junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/./solrtest-SolrExampleStreamingBinaryTest-1370847189744
[junit4:junit4] 1> 51901 [TEST-SolrExampleStreamingBinaryTest.testPivotFacets-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPivotFacets
[junit4:junit4] 1> 51904 [TEST-SolrExampleStreamingBinaryTest.testPivotFacets-seed#[3E85581A9914AB60]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 52078 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onInit: commits: num=1
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_1,generation=1}
[junit4:junit4] 1> 52079 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 1
[junit4:junit4] 1> 52080 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1df31e8 realtime
[junit4:junit4] 1> 52081 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437464501288960)} 0 6
[junit4:junit4] 1> 52085 [qtp20112216-198] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 52086 [qtp20112216-198] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_1,generation=1}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_2,generation=2}
[junit4:junit4] 1> 52087 [qtp20112216-198] INFO org.apache.solr.core.SolrCore – newest commit generation = 2
[junit4:junit4] 1> 52088 [qtp20112216-198] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1885521 main
[junit4:junit4] 1> 52088 [qtp20112216-198] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 52088 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1885521 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 52089 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 52096 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1885521 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4:junit4] 1> 52097 [qtp20112216-198] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 12
[junit4:junit4] 1> 52102 [qtp20112216-199] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=0
[junit4:junit4] 1> 52106 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@bd0b9c
[junit4:junit4] 1> 52362 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 (1437437464537989120), 2 (1437437464540086272), 3 (1437437464541134848), 4 (1437437464542183424), 5 (1437437464542183425), 6 (1437437464543232000), 7 (1437437464543232001), 8 (1437437464544280576), 9 (1437437464544280577), 10 (1437437464545329152), ... (12 adds)]} 0 250
[junit4:junit4] 1> 52363 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 1 is 200
[junit4:junit4] 1> 52364 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@bd0b9c
[junit4:junit4] 1> 52368 [qtp20112216-198] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 52372 [qtp20112216-198] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_2,generation=2}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_3,generation=3}
[junit4:junit4] 1> 52373 [qtp20112216-198] INFO org.apache.solr.core.SolrCore – newest commit generation = 3
[junit4:junit4] 1> 52374 [qtp20112216-198] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@3c7865 main
[junit4:junit4] 1> 52375 [qtp20112216-198] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 52375 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@3c7865 main{StandardDirectoryReader(segments_3:3:nrt _0(4.4):C12)}
[junit4:junit4] 1> 52376 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 52378 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@3c7865 main{StandardDirectoryReader(segments_3:3:nrt _0(4.4):C12)}
[junit4:junit4] 1> 52378 [qtp20112216-198] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 10
[junit4:junit4] 1> 52384 [qtp20112216-199] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4] 1> 52385 [qtp20112216-199] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4] 1> 52387 [qtp20112216-199] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&facet=true&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&rows=0&facet.missing=false&facet.mincount=0&wt=javabin&q=*:*} hits=12 status=0 QTime=3
[junit4:junit4] 1> 52394 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&facet=true&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&rows=0&facet.mincount=0&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&q=*:*} hits=11 status=0 QTime=1
[junit4:junit4] 1> 52396 [TEST-SolrExampleStreamingBinaryTest.testPivotFacets-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPivotFacets
[junit4:junit4] 1> 52407 [TEST-SolrExampleStreamingBinaryTest.testStatistics-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testStatistics
[junit4:junit4] 1> 52412 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@8adab6 realtime
[junit4:junit4] 1> 52412 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437464850464768)} 0 2
[junit4:junit4] 1> 52417 [qtp20112216-198] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 52418 [qtp20112216-198] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_3,generation=3}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_4,generation=4}
[junit4:junit4] 1> 52418 [qtp20112216-198] INFO org.apache.solr.core.SolrCore – newest commit generation = 4
[junit4:junit4] 1> 52419 [qtp20112216-198] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@db8838 main
[junit4:junit4] 1> 52420 [qtp20112216-198] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 52420 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@db8838 main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4] 1> 52421 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 52424 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@db8838 main{StandardDirectoryReader(segments_3:5:nrt)}
[junit4:junit4] 1> 52425 [qtp20112216-198] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 8
[junit4:junit4] 1> 52429 [qtp20112216-199] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=0
[junit4:junit4] 1> 52431 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1aaec5f
[junit4:junit4] 1> 52432 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@13242bc
[junit4:junit4] 1> 52432 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@162e865
[junit4:junit4] 1> 52434 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1032178
[junit4:junit4] 1> 52435 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1032178
[junit4:junit4] 1> 52687 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc0 (1437437464881922048), doc3 (1437437464884019200), doc4 (1437437464885067776), doc5 (1437437464885067777), doc6 (1437437464886116352), doc7 (1437437464886116353), doc8 (1437437464887164928), doc9 (1437437464887164929)]} 0 247
[junit4:junit4] 1> 52688 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: doc0 is 200
[junit4:junit4] 1> 52689 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1aaec5f
[junit4:junit4] 1> 52709 [qtp20112216-198] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc2 (1437437464901844992)]} 0 250
[junit4:junit4] 1> 52709 [qtp20112216-199] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc1 (1437437464902893568)]} 0 249
[junit4:junit4] 1> 52710 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: doc2 is 200
[junit4:junit4] 1> 52711 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@162e865
[junit4:junit4] 1> 52711 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: doc1 is 200
[junit4:junit4] 1> 52712 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@13242bc
[junit4:junit4] 1> 52716 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 52721 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_4,generation=4}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_5,generation=5}
[junit4:junit4] 1> 52721 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 5
[junit4:junit4] 1> 52723 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@5c6a73 main
[junit4:junit4] 1> 52724 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 52724 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@5c6a73 main{StandardDirectoryReader(segments_5:7:nrt _1(4.4):C10)}
[junit4:junit4] 1> 52725 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 52727 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@5c6a73 main{StandardDirectoryReader(segments_5:7:nrt _1(4.4):C10)}
[junit4:junit4] 1> 52727 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 12
[junit4:junit4] 1> 52732 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=10 status=0 QTime=1
[junit4:junit4] 1> 52738 [qtp20112216-198] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&stats=true&rows=0&wt=javabin&stats.field=val_pi&q=*:*} hits=10 status=0 QTime=1
[junit4:junit4] 1> 52744 [qtp20112216-199] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@115fd36 realtime
[junit4:junit4] 1> 52745 [qtp20112216-199] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437465198592000)} 0 3
[junit4:junit4] 1> 52749 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 52750 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_5,generation=5}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_6,generation=6}
[junit4:junit4] 1> 52751 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 6
[junit4:junit4] 1> 52752 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@194324f main
[junit4:junit4] 1> 52753 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 52753 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@194324f main{StandardDirectoryReader(segments_5:9:nrt)}
[junit4:junit4] 1> 52753 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 52755 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@194324f main{StandardDirectoryReader(segments_5:9:nrt)}
[junit4:junit4] 1> 52756 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 7
[junit4:junit4] 1> 52760 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=0
[junit4:junit4] 1> 52762 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@c8bb51
[junit4:junit4] 1> 52762 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1453609
[junit4:junit4] 1> 52762 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@9801c2
[junit4:junit4] 1> 52762 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1fb4a08
[junit4:junit4] 1> 52762 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@26e8e0
[junit4:junit4] 1> 53015 [qtp20112216-198] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc10 (1437437465222709248)]} 0 250
[junit4:junit4] 1> 53015 [qtp20112216-199] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc11 (1437437465223757824)]} 0 249
[junit4:junit4] 1> 53016 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: doc10 is 200
[junit4:junit4] 1> 53017 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: doc11 is 200
[junit4:junit4] 1> 53017 [concurrentUpdateScheduler-1-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@c8bb51
[junit4:junit4] 1> 53017 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc12 (1437437465224806400)]} 0 250
[junit4:junit4] 1> 53017 [concurrentUpdateScheduler-1-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1453609
[junit4:junit4] 1> 53019 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: doc12 is 200
[junit4:junit4] 1> 53019 [concurrentUpdateScheduler-1-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@9801c2
[junit4:junit4] 1> 53029 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc14 (1437437465241583616)]} 0 246
[junit4:junit4] 1> 53030 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: doc14 is 200
[junit4:junit4] 1> 53031 [concurrentUpdateScheduler-1-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@26e8e0
[junit4:junit4] 1> 53032 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[doc13 (1437437465240535040)]} 0 250
[junit4:junit4] 1> 53033 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: doc13 is 200
[junit4:junit4] 1> 53034 [concurrentUpdateScheduler-1-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1fb4a08
[junit4:junit4] 1> 53039 [qtp20112216-198] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 53045 [qtp20112216-198] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_6,generation=6}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_7,generation=7}
[junit4:junit4] 1> 53046 [qtp20112216-198] INFO org.apache.solr.core.SolrCore – newest commit generation = 7
[junit4:junit4] 1> 53049 [qtp20112216-198] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@147b3d main
[junit4:junit4] 1> 53050 [qtp20112216-198] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 53050 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@147b3d main{StandardDirectoryReader(segments_7:13:nrt _2(4.4):C4 _3(4.4):C1)}
[junit4:junit4] 1> 53051 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 53053 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@147b3d main{StandardDirectoryReader(segments_7:13:nrt _2(4.4):C4 _3(4.4):C1)}
[junit4:junit4] 1> 53053 [qtp20112216-198] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 15
[junit4:junit4] 1> 53058 [qtp20112216-199] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=5 status=0 QTime=1
[junit4:junit4] 1> 53062 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&stats=true&rows=0&wt=javabin&stats.field=val_pi&q=*:*} hits=5 status=0 QTime=0
[junit4:junit4] 1> 53070 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@ffd5c0 realtime
[junit4:junit4] 1> 53071 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437465540427776)} 0 3
[junit4:junit4] 1> 68071 [commitScheduler-115-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 68072 [commitScheduler-115-thread-1] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_7,generation=7}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_8,generation=8}
[junit4:junit4] 1> 68072 [commitScheduler-115-thread-1] INFO org.apache.solr.core.SolrCore – newest commit generation = 8
[junit4:junit4] 1> 68073 [commitScheduler-115-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 253345 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 253345 [TEST-SolrExampleStreamingBinaryTest.testStatistics-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testStatistics
[junit4:junit4] 1> 253347 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_8,generation=8}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_9,generation=9}
[junit4:junit4] 1> 253347 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 9
[junit4:junit4] 1> 253348 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@925228 main
[junit4:junit4] 1> 253349 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 253349 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@925228 main{StandardDirectoryReader(segments_7:15:nrt)}
[junit4:junit4] 1> 253350 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 253352 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@925228 main{StandardDirectoryReader(segments_7:15:nrt)}
[junit4:junit4] 1> 253352 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 7
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExampleStreamingBinaryTest -Dtests.method=testStatistics -Dtests.seed=3E85581A9914AB60 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr_BE -Dtests.timezone=Asia/Irkutsk -Dtests.file.encoding=UTF-8
[junit4:junit4] ERROR 201s J0 | SolrExampleStreamingBinaryTest.testStatistics <<<
[junit4:junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: https://127.0.0.1:33214/solr/collection1
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([3E85581A9914AB60:C92C6B7E35397188]:0)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:436)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:180)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer.request(ConcurrentUpdateSolrServer.java:256)
[junit4:junit4] > at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:168)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrServer.commit(SolrServer.java:146)
[junit4:junit4] > at org.apache.solr.client.solrj.SolrExampleTests.testStatistics(SolrExampleTests.java:851)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4:junit4] > Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond
[junit4:junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:95)
[junit4:junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:62)
[junit4:junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:254)
[junit4:junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:289)
[junit4:junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:252)
[junit4:junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:191)
[junit4:junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:300)
[junit4:junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:127)
[junit4:junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:717)
[junit4:junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:522)
[junit4:junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:906)
[junit4:junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:805)
[junit4:junit4] > at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:784)
[junit4:junit4] > at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:365)
[junit4:junit4] > ... 46 more
[junit4:junit4] 1> 253395 [TEST-SolrExampleStreamingBinaryTest.testChineseDefaults-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testChineseDefaults
[junit4:junit4] 1> 253405 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437675607949312)} 0 1
[junit4:junit4] 1> 253409 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 253409 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_9,generation=9}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_a,generation=10}
[junit4:junit4] 1> 253410 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 10
[junit4:junit4] 1> 253410 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@14f8ac7 main
[junit4:junit4] 1> 253411 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 253411 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@14f8ac7 main{StandardDirectoryReader(segments_7:15:nrt)}
[junit4:junit4] 1> 253412 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 253414 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@14f8ac7 main{StandardDirectoryReader(segments_7:15:nrt)}
[junit4:junit4] 1> 253415 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 6
[junit4:junit4] 1> 253419 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=0
[junit4:junit4] 1> 253420 [TEST-SolrExampleStreamingBinaryTest.testChineseDefaults-seed#[3E85581A9914AB60]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – blocking for commit/optimize
[junit4:junit4] 1> 253424 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 253426 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_a,generation=10}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_b,generation=11}
[junit4:junit4] 1> 253427 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – newest commit generation = 11
[junit4:junit4] 1> 253428 [qtp20112216-212] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@145284e main
[junit4:junit4] 1> 253428 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 253428 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@145284e main{StandardDirectoryReader(segments_b:17:nrt _4(4.4):C1)}
[junit4:junit4] 1> 253429 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 253431 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@145284e main{StandardDirectoryReader(segments_b:17:nrt _4(4.4):C1)}
[junit4:junit4] 1> 253431 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {add=[42 (1437437675627872256)],commit=} 0 8
[junit4:junit4] 1> 253436 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=北京大学} hits=1 status=0 QTime=1
[junit4:junit4] 1> 253437 [TEST-SolrExampleStreamingBinaryTest.testChineseDefaults-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testChineseDefaults
[junit4:junit4] 1> 253445 [TEST-SolrExampleStreamingBinaryTest.testFaceting-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testFaceting
[junit4:junit4] 1> 253448 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1065d59 realtime
[junit4:junit4] 1> 253449 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437675653038080)} 0 2
[junit4:junit4] 1> 253452 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 253453 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_b,generation=11}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_c,generation=12}
[junit4:junit4] 1> 253453 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – newest commit generation = 12
[junit4:junit4] 1> 253454 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@4b0481 main
[junit4:junit4] 1> 253454 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 253454 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@4b0481 main{StandardDirectoryReader(segments_b:19:nrt)}
[junit4:junit4] 1> 253455 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 253457 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@4b0481 main{StandardDirectoryReader(segments_b:19:nrt)}
[junit4:junit4] 1> 253457 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 5
[junit4:junit4] 1> 253461 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=0
[junit4:junit4] 1> 253462 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@764c49
[junit4:junit4] 1> 253716 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 (1437437675672961024), 2 (1437437675674009600), 3 (1437437675675058176), 4 (1437437675675058177), 5 (1437437675676106752), 6 (1437437675677155328), 7 (1437437675677155329), 8 (1437437675677155330), 9 (1437437675678203904), 10 (1437437675678203905)]} 0 251
[junit4:junit4] 1> 253717 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 1 is 200
[junit4:junit4] 1> 253717 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@764c49
[junit4:junit4] 1> 253720 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 253727 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_c,generation=12}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_d,generation=13}
[junit4:junit4] 1> 253728 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 13
[junit4:junit4] 1> 253729 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@a87960 main
[junit4:junit4] 1> 253730 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@a87960 main{StandardDirectoryReader(segments_d:21:nrt _5(4.4):C10)}
[junit4:junit4] 1> 253730 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 253730 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 253732 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@a87960 main{StandardDirectoryReader(segments_d:21:nrt _5(4.4):C10)}
[junit4:junit4] 1> 253733 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 13
[junit4:junit4] 1> 253737 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=features,memSize=4564,tindexSize=44,time=1,phase1=1,nTerms=4,bigTerms=2,termInstances=4,uses=0}
[junit4:junit4] 1> 253737 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&facet.field=features&facet=true&rows=0&facet.mincount=0&wt=javabin&q=*:*} hits=10 status=0 QTime=1
[junit4:junit4] 1> 253742 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&facet.field=features&facet=true&rows=0&facet.mincount=0&wt=javabin&fq=features:two&q=*:*} hits=5 status=0 QTime=1
[junit4:junit4] 1> 253746 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&facet.field=features&facet=true&rows=0&facet.mincount=4&wt=javabin&fq=features:two&q=*:*} hits=5 status=0 QTime=1
[junit4:junit4] 1> 253750 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&facet.field=features&facet=true&rows=0&facet.mincount=-1&wt=javabin&fq=features:two&q=*:*} hits=5 status=0 QTime=1
[junit4:junit4] 1> 253751 [TEST-SolrExampleStreamingBinaryTest.testFaceting-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testFaceting
[junit4:junit4] 1> 253759 [TEST-SolrExampleStreamingBinaryTest.testUpdateField-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateField
[junit4:junit4] 1> 253763 [qtp20112216-212] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1ab6a78 realtime
[junit4:junit4] 1> 253763 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437675983339520)} 0 2
[junit4:junit4] 1> 253767 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 253768 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_d,generation=13}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_e,generation=14}
[junit4:junit4] 1> 253769 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 14
[junit4:junit4] 1> 253769 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@16df394 main
[junit4:junit4] 1> 253770 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@16df394 main{StandardDirectoryReader(segments_d:23:nrt)}
[junit4:junit4] 1> 253771 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 253770 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 253773 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@16df394 main{StandardDirectoryReader(segments_d:23:nrt)}
[junit4:junit4] 1> 253774 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 7
[junit4:junit4] 1> 253779 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@8e2739
[junit4:junit4] 1> 254032 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[unique (1437437676004311040)]} 0 250
[junit4:junit4] 1> 254033 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: unique is 200
[junit4:junit4] 1> 254034 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@8e2739
[junit4:junit4] 1> 254037 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 254040 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_e,generation=14}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_f,generation=15}
[junit4:junit4] 1> 254041 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 15
[junit4:junit4] 1> 254043 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1ea0853 main
[junit4:junit4] 1> 254043 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1ea0853 main{StandardDirectoryReader(segments_f:25:nrt _6(4.4):C1)}
[junit4:junit4] 1> 254044 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 254044 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 254046 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1ea0853 main{StandardDirectoryReader(segments_f:25:nrt _6(4.4):C1)}
[junit4:junit4] 1> 254046 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 9
[junit4:junit4] 1> 254051 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&fl=id,price_f,name,_version_&wt=javabin&q=*:*} hits=1 status=0 QTime=1
[junit4:junit4] 1> 254052 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@18cb84c
[junit4:junit4] 1> 254055 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {} 0 0
[junit4:junit4] 1> 254305 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: unique is 409
[junit4:junit4] 1> 254305 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@18cb84c
[junit4:junit4] 1> 254308 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 254309 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_f,generation=15}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_g,generation=16}
[junit4:junit4] 1> 254310 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – newest commit generation = 16
[junit4:junit4] 1> 254311 [qtp20112216-212] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@a56a08 main
[junit4:junit4] 1> 254312 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 254312 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@a56a08 main{StandardDirectoryReader(segments_f:25:nrt _6(4.4):C1)}
[junit4:junit4] 1> 254313 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 254314 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@a56a08 main{StandardDirectoryReader(segments_f:25:nrt _6(4.4):C1)}
[junit4:junit4] 1> 254315 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 7
[junit4:junit4] 1> 254316 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@128b1b0
[junit4:junit4] 1> 254569 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[unique (1437437676568444928)]} 0 250
[junit4:junit4] 1> 254570 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: unique is 200
[junit4:junit4] 1> 254571 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@128b1b0
[junit4:junit4] 1> 254574 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 254576 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_g,generation=16}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_h,generation=17}
[junit4:junit4] 1> 254577 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 17
[junit4:junit4] 1> 254578 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1d371f6 main
[junit4:junit4] 1> 254579 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1d371f6 main{StandardDirectoryReader(segments_h:29:nrt _7(4.4):C1)}
[junit4:junit4] 1> 254579 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 254580 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 254582 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1d371f6 main{StandardDirectoryReader(segments_h:29:nrt _7(4.4):C1)}
[junit4:junit4] 1> 254583 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 9
[junit4:junit4] 1> 254587 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&fl=id,price_f,name,_version_&wt=javabin&q=*:*} hits=1 status=0 QTime=0
[junit4:junit4] 1> 254589 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@b9668
[junit4:junit4] 1> 254842 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[unique (1437437676853657600)]} 0 250
[junit4:junit4] 1> 254844 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: unique is 200
[junit4:junit4] 1> 254844 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@b9668
[junit4:junit4] 1> 254847 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 254849 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_h,generation=17}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_i,generation=18}
[junit4:junit4] 1> 254850 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 18
[junit4:junit4] 1> 254851 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@14fe28e main
[junit4:junit4] 1> 254852 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@14fe28e main{StandardDirectoryReader(segments_i:33:nrt _8(4.4):C1)}
[junit4:junit4] 1> 254853 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 254852 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 254854 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@14fe28e main{StandardDirectoryReader(segments_i:33:nrt _8(4.4):C1)}
[junit4:junit4] 1> 254855 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 8
[junit4:junit4] 1> 254859 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&fl=id,price_f,name,_version_&wt=javabin&q=*:*} hits=1 status=0 QTime=0
[junit4:junit4] 1> 254861 [TEST-SolrExampleStreamingBinaryTest.testUpdateField-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUpdateField
[junit4:junit4] 1> 254872 [TEST-SolrExampleStreamingBinaryTest.testUnicode-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUnicode
[junit4:junit4] 1> 254876 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1eb7fcb realtime
[junit4:junit4] 1> 254877 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437677151453184)} 0 2
[junit4:junit4] 1> 254879 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@145c13f
[junit4:junit4] 1> 255131 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437677157744640), 1 (1437437677157744641), 2 (1437437677158793216), 3 (1437437677158793217), 4 (1437437677159841792), 5 (1437437677159841793), 6 (1437437677159841794), 7 (1437437677160890368), 8 (1437437677160890369), 9 (1437437677161938944)]} 0 250
[junit4:junit4] 1> 255133 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 255133 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@145c13f
[junit4:junit4] 1> 255136 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 255139 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_i,generation=18}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_j,generation=19}
[junit4:junit4] 1> 255140 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 19
[junit4:junit4] 1> 255141 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1444cf6 main
[junit4:junit4] 1> 255143 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1444cf6 main{StandardDirectoryReader(segments_j:37:nrt _9(4.4):C10)}
[junit4:junit4] 1> 255143 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 255143 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 255145 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1444cf6 main{StandardDirectoryReader(segments_j:37:nrt _9(4.4):C10)}
[junit4:junit4] 1> 255146 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 10
[junit4:junit4] 1> 255150 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=10&wt=javabin&q=*:*} hits=10 status=0 QTime=0
[junit4:junit4] 1> 255155 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@18ff7f1 realtime
[junit4:junit4] 1> 255155 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437677442957312)} 0 1
[junit4:junit4] 1> 255158 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1a34703
[junit4:junit4] 1> 255411 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437677451345920), 1 (1437437677452394496), 2 (1437437677452394497), 3 (1437437677452394498), 4 (1437437677453443072), 5 (1437437677453443073), 6 (1437437677454491648), 7 (1437437677454491649), 8 (1437437677454491650), 9 (1437437677455540224), ... (30 adds)]} 0 249
[junit4:junit4] 1> 255412 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 255413 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1a34703
[junit4:junit4] 1> 255416 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 255419 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_j,generation=19}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_k,generation=20}
[junit4:junit4] 1> 255420 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – newest commit generation = 20
[junit4:junit4] 1> 255421 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@165edd6 main
[junit4:junit4] 1> 255422 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@165edd6 main{StandardDirectoryReader(segments_k:41:nrt _a(4.4):C30)}
[junit4:junit4] 1> 255422 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 255422 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 255424 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@165edd6 main{StandardDirectoryReader(segments_k:41:nrt _a(4.4):C30)}
[junit4:junit4] 1> 255425 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 10
[junit4:junit4] 1> 255429 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=30&wt=javabin&q=*:*} hits=30 status=0 QTime=1
[junit4:junit4] 1> 255435 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@f67bbe realtime
[junit4:junit4] 1> 255436 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437677736558592)} 0 2
[junit4:junit4] 1> 255438 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@13ed636
[junit4:junit4] 1> 255691 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437677744947200), 1 (1437437677745995776), 2 (1437437677745995777), 3 (1437437677747044352), 4 (1437437677747044353), 5 (1437437677748092928), 6 (1437437677748092929), 7 (1437437677748092930), 8 (1437437677749141504), 9 (1437437677749141505), ... (30 adds)]} 0 249
[junit4:junit4] 1> 255693 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 255693 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@13ed636
[junit4:junit4] 1> 255696 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 255701 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_k,generation=20}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_l,generation=21}
[junit4:junit4] 1> 255701 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – newest commit generation = 21
[junit4:junit4] 1> 255703 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@e1458f main
[junit4:junit4] 1> 255704 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@e1458f main{StandardDirectoryReader(segments_l:45:nrt _b(4.4):C30)}
[junit4:junit4] 1> 255705 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 255705 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 255707 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@e1458f main{StandardDirectoryReader(segments_l:45:nrt _b(4.4):C30)}
[junit4:junit4] 1> 255707 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 11
[junit4:junit4] 1> 255711 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=30&wt=javabin&q=*:*} hits=30 status=0 QTime=0
[junit4:junit4] 1> 255719 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1627584 realtime
[junit4:junit4] 1> 255720 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437678034354176)} 0 3
[junit4:junit4] 1> 255722 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1a6ba2f
[junit4:junit4] 1> 255975 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437678041694208), 1 (1437437678042742784), 2 (1437437678042742785), 3 (1437437678042742786), 4 (1437437678043791360), 5 (1437437678043791361), 6 (1437437678043791362), 7 (1437437678044839936), 8 (1437437678044839937), 9 (1437437678044839938), ... (13 adds)]} 0 250
[junit4:junit4] 1> 255976 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 255977 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1a6ba2f
[junit4:junit4] 1> 255979 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 255983 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_l,generation=21}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_m,generation=22}
[junit4:junit4] 1> 255984 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 22
[junit4:junit4] 1> 255985 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@826656 main
[junit4:junit4] 1> 255986 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@826656 main{StandardDirectoryReader(segments_m:49:nrt _c(4.4):C13)}
[junit4:junit4] 1> 255987 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 255987 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 255989 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@826656 main{StandardDirectoryReader(segments_m:49:nrt _c(4.4):C13)}
[junit4:junit4] 1> 255989 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 10
[junit4:junit4] 1> 255994 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=13&wt=javabin&q=*:*} hits=13 status=0 QTime=0
[junit4:junit4] 1> 256000 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@36e30a realtime
[junit4:junit4] 1> 256001 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437678329004032)} 0 2
[junit4:junit4] 1> 256002 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@41c99d
[junit4:junit4] 1> 256255 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437678335295488), 1 (1437437678336344064)]} 0 250
[junit4:junit4] 1> 256257 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 256257 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@41c99d
[junit4:junit4] 1> 256260 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 256262 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_m,generation=22}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_n,generation=23}
[junit4:junit4] 1> 256263 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – newest commit generation = 23
[junit4:junit4] 1> 256264 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1f692dd main
[junit4:junit4] 1> 256264 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1f692dd main{StandardDirectoryReader(segments_n:53:nrt _d(4.4):C2)}
[junit4:junit4] 1> 256265 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 256265 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 256267 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1f692dd main{StandardDirectoryReader(segments_n:53:nrt _d(4.4):C2)}
[junit4:junit4] 1> 256268 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 8
[junit4:junit4] 1> 256272 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=2&wt=javabin&q=*:*} hits=2 status=0 QTime=1
[junit4:junit4] 1> 256276 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@100d7bd realtime
[junit4:junit4] 1> 256277 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437678619459584)} 0 2
[junit4:junit4] 1> 256278 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1ed1e09
[junit4:junit4] 1> 256531 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437678624702464), 1 (1437437678625751040), 2 (1437437678626799616), 3 (1437437678626799617), 4 (1437437678626799618), 5 (1437437678627848192)]} 0 250
[junit4:junit4] 1> 256533 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 256533 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1ed1e09
[junit4:junit4] 1> 256536 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 256538 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_n,generation=23}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_o,generation=24}
[junit4:junit4] 1> 256539 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 24
[junit4:junit4] 1> 256540 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@57df7f main
[junit4:junit4] 1> 256541 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 256541 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@57df7f main{StandardDirectoryReader(segments_o:57:nrt _e(4.4):C6)}
[junit4:junit4] 1> 256542 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 256544 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@57df7f main{StandardDirectoryReader(segments_o:57:nrt _e(4.4):C6)}
[junit4:junit4] 1> 256545 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 9
[junit4:junit4] 1> 256549 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=6&wt=javabin&q=*:*} hits=6 status=0 QTime=0
[junit4:junit4] 1> 256554 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@2dc4ef realtime
[junit4:junit4] 1> 256554 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437678909915136)} 0 1
[junit4:junit4] 1> 256556 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@81e264
[junit4:junit4] 1> 256809 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437678917255168), 1 (1437437678917255169), 2 (1437437678918303744), 3 (1437437678918303745), 4 (1437437678919352320), 5 (1437437678919352321), 6 (1437437678919352322), 7 (1437437678920400896), 8 (1437437678920400897), 9 (1437437678920400898), ... (19 adds)]} 0 249
[junit4:junit4] 1> 256810 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 256811 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@81e264
[junit4:junit4] 1> 256814 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 256821 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_o,generation=24}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_p,generation=25}
[junit4:junit4] 1> 256821 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 25
[junit4:junit4] 1> 256822 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@fc7e50 main
[junit4:junit4] 1> 256823 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@fc7e50 main{StandardDirectoryReader(segments_p:61:nrt _f(4.4):C19)}
[junit4:junit4] 1> 256823 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 256824 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 256827 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@fc7e50 main{StandardDirectoryReader(segments_p:61:nrt _f(4.4):C19)}
[junit4:junit4] 1> 256828 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 14
[junit4:junit4] 1> 256832 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=19&wt=javabin&q=*:*} hits=19 status=0 QTime=1
[junit4:junit4] 1> 256838 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1e38622 realtime
[junit4:junit4] 1> 256838 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437679207710720)} 0 1
[junit4:junit4] 1> 256840 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@f65ed9
[junit4:junit4] 1> 257092 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437679212953600), 1 (1437437679214002176), 2 (1437437679215050752)]} 0 250
[junit4:junit4] 1> 257093 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 257094 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@f65ed9
[junit4:junit4] 1> 257097 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257099 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_p,generation=25}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_q,generation=26}
[junit4:junit4] 1> 257099 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – newest commit generation = 26
[junit4:junit4] 1> 257100 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@17f44c7 main
[junit4:junit4] 1> 257101 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257101 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@17f44c7 main{StandardDirectoryReader(segments_q:65:nrt _g(4.4):C3)}
[junit4:junit4] 1> 257102 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257104 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@17f44c7 main{StandardDirectoryReader(segments_q:65:nrt _g(4.4):C3)}
[junit4:junit4] 1> 257105 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 9
[junit4:junit4] 1> 257108 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=3&wt=javabin&q=*:*} hits=3 status=0 QTime=0
[junit4:junit4] 1> 257113 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@15a29d1 realtime
[junit4:junit4] 1> 257113 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437679496069120)} 0 1
[junit4:junit4] 1> 257116 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6b7b3b
[junit4:junit4] 1> 257369 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437679503409152), 1 (1437437679504457728), 2 (1437437679504457729), 3 (1437437679505506304), 4 (1437437679505506305), 5 (1437437679505506306), 6 (1437437679505506307), 7 (1437437679506554880), 8 (1437437679506554881), 9 (1437437679506554882), ... (28 adds)]} 0 250
[junit4:junit4] 1> 257370 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 257370 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6b7b3b
[junit4:junit4] 1> 257373 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257376 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_q,generation=26}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_r,generation=27}
[junit4:junit4] 1> 257377 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – newest commit generation = 27
[junit4:junit4] 1> 257378 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@15ccc1e main
[junit4:junit4] 1> 257379 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@15ccc1e main{StandardDirectoryReader(segments_r:69:nrt _h(4.4):C28)}
[junit4:junit4] 1> 257379 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257379 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257381 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@15ccc1e main{StandardDirectoryReader(segments_r:69:nrt _h(4.4):C28)}
[junit4:junit4] 1> 257382 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 9
[junit4:junit4] 1> 257386 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=28&wt=javabin&q=*:*} hits=28 status=0 QTime=1
[junit4:junit4] 1> 257392 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1983165 realtime
[junit4:junit4] 1> 257393 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437679788621824)} 0 2
[junit4:junit4] 1> 257394 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1b72b29
[junit4:junit4] 1> 257647 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437679794913280), 1 (1437437679795961856), 2 (1437437679795961857), 3 (1437437679795961858), 4 (1437437679795961859), 5 (1437437679797010432), 6 (1437437679797010433), 7 (1437437679797010434), 8 (1437437679798059008)]} 0 250
[junit4:junit4] 1> 257648 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 257648 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1b72b29
[junit4:junit4] 1> 257651 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257654 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_r,generation=27}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_s,generation=28}
[junit4:junit4] 1> 257654 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 28
[junit4:junit4] 1> 257655 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@e913bf main
[junit4:junit4] 1> 257656 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@e913bf main{StandardDirectoryReader(segments_s:73:nrt _i(4.4):C9)}
[junit4:junit4] 1> 257656 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257656 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257658 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@e913bf main{StandardDirectoryReader(segments_s:73:nrt _i(4.4):C9)}
[junit4:junit4] 1> 257658 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 7
[junit4:junit4] 1> 257662 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=9&wt=javabin&q=*:*} hits=9 status=0 QTime=0
[junit4:junit4] 1> 257667 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@bcf1dd realtime
[junit4:junit4] 1> 257667 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437680076980224)} 0 1
[junit4:junit4] 1> 257670 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6078f9
[junit4:junit4] 1> 257922 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437680084320256), 1 (1437437680084320257), 2 (1437437680085368832), 3 (1437437680085368833), 4 (1437437680085368834), 5 (1437437680085368835), 6 (1437437680086417408), 7 (1437437680086417409), 8 (1437437680086417410), 9 (1437437680087465984), ... (20 adds)]} 0 249
[junit4:junit4] 1> 257924 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 257924 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6078f9
[junit4:junit4] 1> 257927 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 257929 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_s,generation=28}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_t,generation=29}
[junit4:junit4] 1> 257930 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 29
[junit4:junit4] 1> 257931 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@d0b815 main
[junit4:junit4] 1> 257931 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@d0b815 main{StandardDirectoryReader(segments_t:77:nrt _j(4.4):C20)}
[junit4:junit4] 1> 257932 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 257932 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 257933 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@d0b815 main{StandardDirectoryReader(segments_t:77:nrt _j(4.4):C20)}
[junit4:junit4] 1> 257934 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 7
[junit4:junit4] 1> 257938 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=20&wt=javabin&q=*:*} hits=20 status=0 QTime=1
[junit4:junit4] 1> 257944 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@c14c44 realtime
[junit4:junit4] 1> 257944 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437680367435776)} 0 1
[junit4:junit4] 1> 257947 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@98e57a
[junit4:junit4] 1> 258200 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[0 (1437437680374775808), 1 (1437437680375824384), 2 (1437437680375824385), 3 (1437437680375824386), 4 (1437437680376872960), 5 (1437437680376872961), 6 (1437437680376872962), 7 (1437437680377921536), 8 (1437437680377921537), 9 (1437437680377921538), ... (30 adds)]} 0 250
[junit4:junit4] 1> 258201 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 0 is 200
[junit4:junit4] 1> 258202 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@98e57a
[junit4:junit4] 1> 258204 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 258208 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_t,generation=29}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_u,generation=30}
[junit4:junit4] 1> 258208 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – newest commit generation = 30
[junit4:junit4] 1> 258209 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@63b4cb main
[junit4:junit4] 1> 258210 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@63b4cb main{StandardDirectoryReader(segments_u:81:nrt _k(4.4):C30)}
[junit4:junit4] 1> 258210 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 258210 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 258212 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@63b4cb main{StandardDirectoryReader(segments_u:81:nrt _k(4.4):C30)}
[junit4:junit4] 1> 258213 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 9
[junit4:junit4] 1> 258216 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&rows=30&wt=javabin&q=*:*} hits=30 status=0 QTime=0
[junit4:junit4] 1> 258220 [TEST-SolrExampleStreamingBinaryTest.testUnicode-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUnicode
[junit4:junit4] 1> 258228 [TEST-SolrExampleStreamingBinaryTest.testCommitWithinOnAdd-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testCommitWithinOnAdd
[junit4:junit4] 1> 258232 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1b275e4 realtime
[junit4:junit4] 1> 258233 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437680669425664)} 0 2
[junit4:junit4] 1> 258236 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 258237 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_u,generation=30}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_v,generation=31}
[junit4:junit4] 1> 258237 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – newest commit generation = 31
[junit4:junit4] 1> 258238 [qtp20112216-212] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@13b8efa main
[junit4:junit4] 1> 258239 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@13b8efa main{StandardDirectoryReader(segments_u:83:nrt)}
[junit4:junit4] 1> 258239 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 258239 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 258241 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@13b8efa main{StandardDirectoryReader(segments_u:83:nrt)}
[junit4:junit4] 1> 258242 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 6
[junit4:junit4] 1> 258246 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=0
[junit4:junit4] 1> 258248 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1abe031
[junit4:junit4] 1> 258250 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=0
[junit4:junit4] 1> 258507 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id3 (1437437680696688640)]} 0 250
[junit4:junit4] 1> 258509 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: id3 is 200
[junit4:junit4] 1> 258509 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1abe031
[junit4:junit4] 1> 258759 [commitScheduler-116-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4] 1> 258762 [commitScheduler-116-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1a24fb3 main
[junit4:junit4] 1> 258763 [commitScheduler-116-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 258763 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1a24fb3 main{StandardDirectoryReader(segments_v:85:nrt _l(4.4):C1)}
[junit4:junit4] 1> 258763 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 258765 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1a24fb3 main{StandardDirectoryReader(segments_v:85:nrt _l(4.4):C1)}
[junit4:junit4] 1> 259255 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=id:id3} hits=1 status=0 QTime=0
[junit4:junit4] 1> 259257 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1e7698e
[junit4:junit4] 1> 259510 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id4 (1437437681748410368)]} 0 251
[junit4:junit4] 1> 259511 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: id4 is 200
[junit4:junit4] 1> 259511 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1e7698e
[junit4:junit4] 1> 259761 [commitScheduler-116-thread-1] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4:junit4] 1> 259764 [commitScheduler-116-thread-1] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@19a7041 main
[junit4:junit4] 1> 259764 [commitScheduler-116-thread-1] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 259765 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@19a7041 main{StandardDirectoryReader(segments_v:87:nrt _l(4.4):C1 _m(4.4):C1)}
[junit4:junit4] 1> 259765 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 259767 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@19a7041 main{StandardDirectoryReader(segments_v:87:nrt _l(4.4):C1 _m(4.4):C1)}
[junit4:junit4] 1> 260260 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=id:id4} hits=1 status=0 QTime=1
[junit4:junit4] 1> 260261 [TEST-SolrExampleStreamingBinaryTest.testCommitWithinOnAdd-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testCommitWithinOnAdd
[junit4:junit4] 1> 260269 [TEST-SolrExampleStreamingBinaryTest.testUpdateMultiValuedField-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateMultiValuedField
[junit4:junit4] 1> 260269 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1d6e7b
[junit4:junit4] 1> 260522 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[123 (1437437682809569280)]} 0 250
[junit4:junit4] 1> 260523 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 123 is 200
[junit4:junit4] 1> 260524 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1d6e7b
[junit4:junit4] 1> 260527 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 260529 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_v,generation=31}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_w,generation=32}
[junit4:junit4] 1> 260530 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 32
[junit4:junit4] 1> 260531 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@9bb13e main
[junit4:junit4] 1> 260531 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 260531 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@9bb13e main{StandardDirectoryReader(segments_w:89:nrt _l(4.4):C1 _m(4.4):C1 _n(4.4):C1)}
[junit4:junit4] 1> 260532 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 260533 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@9bb13e main{StandardDirectoryReader(segments_w:89:nrt _l(4.4):C1 _m(4.4):C1 _n(4.4):C1)}
[junit4:junit4] 1> 260534 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 8
[junit4:junit4] 1> 260538 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=id:123} hits=1 status=0 QTime=1
[junit4:junit4] 1> 260539 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1cff337
[junit4:junit4] 1> 260792 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[123 (1437437683092684800)]} 0 250
[junit4:junit4] 1> 260793 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 123 is 200
[junit4:junit4] 1> 260793 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1cff337
[junit4:junit4] 1> 260796 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 260799 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_w,generation=32}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_x,generation=33}
[junit4:junit4] 1> 260799 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 33
[junit4:junit4] 1> 260800 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@14746e3 main
[junit4:junit4] 1> 260801 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 260801 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@14746e3 main{StandardDirectoryReader(segments_x:93:nrt _l(4.4):C1 _m(4.4):C1 _o(4.4):C1)}
[junit4:junit4] 1> 260802 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 260803 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@14746e3 main{StandardDirectoryReader(segments_x:93:nrt _l(4.4):C1 _m(4.4):C1 _o(4.4):C1)}
[junit4:junit4] 1> 260804 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 8
[junit4:junit4] 1> 260808 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=id:123} hits=1 status=0 QTime=1
[junit4:junit4] 1> 260809 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@11d2657
[junit4:junit4] 1> 261062 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[123 (1437437683375800320)]} 0 250
[junit4:junit4] 1> 261063 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 123 is 200
[junit4:junit4] 1> 261063 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@11d2657
[junit4:junit4] 1> 261066 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 261069 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_x,generation=33}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_y,generation=34}
[junit4:junit4] 1> 261069 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – newest commit generation = 34
[junit4:junit4] 1> 261078 [qtp20112216-212] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1265472 main
[junit4:junit4] 1> 261078 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 261078 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1265472 main{StandardDirectoryReader(segments_y:97:nrt _l(4.4):C1 _m(4.4):C1 _p(4.4):C1)}
[junit4:junit4] 1> 261079 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 261081 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1265472 main{StandardDirectoryReader(segments_y:97:nrt _l(4.4):C1 _m(4.4):C1 _p(4.4):C1)}
[junit4:junit4] 1> 261081 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 15
[junit4:junit4] 1> 261085 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=id:123} hits=1 status=0 QTime=0
[junit4:junit4] 1> 261087 [TEST-SolrExampleStreamingBinaryTest.testUpdateMultiValuedField-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUpdateMultiValuedField
[junit4:junit4] 1> 261094 [TEST-SolrExampleStreamingBinaryTest.testPingHandler-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPingHandler
[junit4:junit4] 1> 261099 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@22aab7 realtime
[junit4:junit4] 1> 261100 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437683674644480)} 0 3
[junit4:junit4] 1> 261104 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 261104 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_y,generation=34}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_z,generation=35}
[junit4:junit4] 1> 261105 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 35
[junit4:junit4] 1> 261106 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@d4903f main
[junit4:junit4] 1> 261106 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 261106 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@d4903f main{StandardDirectoryReader(segments_y:99:nrt)}
[junit4:junit4] 1> 261107 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 261109 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@d4903f main{StandardDirectoryReader(segments_y:99:nrt)}
[junit4:junit4] 1> 261109 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 5
[junit4:junit4] 1> 261113 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=0
[junit4:junit4] 1> 261117 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/ping params={version=2&wt=javabin} hits=0 status=0 QTime=1
[junit4:junit4] 1> 261117 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/admin/ping params={version=2&wt=javabin} status=0 QTime=1
[junit4:junit4] 1> 261118 [TEST-SolrExampleStreamingBinaryTest.testPingHandler-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPingHandler
[junit4:junit4] 1> 261126 [TEST-SolrExampleStreamingBinaryTest.testPivotFacetsMissing-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testPivotFacetsMissing
[junit4:junit4] 1> 261129 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437683707150336)} 0 1
[junit4:junit4] 1> 261132 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 261133 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_z,generation=35}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_10,generation=36}
[junit4:junit4] 1> 261133 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 36
[junit4:junit4] 1> 261134 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1988f59 main
[junit4:junit4] 1> 261134 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 261134 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1988f59 main{StandardDirectoryReader(segments_y:99:nrt)}
[junit4:junit4] 1> 261135 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 261137 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1988f59 main{StandardDirectoryReader(segments_y:99:nrt)}
[junit4:junit4] 1> 261137 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 5
[junit4:junit4] 1> 261141 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=1
[junit4:junit4] 1> 261142 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1088ce3
[junit4:junit4] 1> 261394 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[1 (1437437683723927552), 2 (1437437683726024704), 3 (1437437683726024705), 4 (1437437683726024706), 5 (1437437683727073280), 6 (1437437683727073281), 7 (1437437683728121856), 8 (1437437683728121857), 9 (1437437683729170432), 10 (1437437683729170433), ... (12 adds)]} 0 250
[junit4:junit4] 1> 261396 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: 1 is 200
[junit4:junit4] 1> 261396 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1088ce3
[junit4:junit4] 1> 261399 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 261402 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_10,generation=36}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_11,generation=37}
[junit4:junit4] 1> 261402 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 37
[junit4:junit4] 1> 261403 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1dc73e1 main
[junit4:junit4] 1> 261404 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1dc73e1 main{StandardDirectoryReader(segments_11:101:nrt _q(4.4):C12)}
[junit4:junit4] 1> 261404 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 261404 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 261406 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1dc73e1 main{StandardDirectoryReader(segments_11:101:nrt _q(4.4):C12)}
[junit4:junit4] 1> 261407 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 8
[junit4:junit4] 1> 261411 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=features,memSize=4521,tindexSize=43,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4] 1> 261412 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – UnInverted multi-valued field {field=cat,memSize=4515,tindexSize=41,time=0,phase1=0,nTerms=2,bigTerms=2,termInstances=0,uses=0}
[junit4:junit4] 1> 261414 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&facet=true&facet.pivot=features,cat&facet.pivot=cat,features&facet.pivot=features,cat,inStock&rows=0&facet.missing=true&facet.mincount=0&wt=javabin&q=*:*} hits=12 status=0 QTime=4
[junit4:junit4] 1> 261419 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&facet=true&facet.pivot={!ex%3Dmytag+key%3Dmykey}features,cat&rows=0&facet.mincount=0&wt=javabin&fq={!tag%3Dmytag}-(features:bbb+AND+cat:a+AND+inStock:true)&q=*:*} hits=11 status=0 QTime=1
[junit4:junit4] 1> 261420 [TEST-SolrExampleStreamingBinaryTest.testPivotFacetsMissing-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testPivotFacetsMissing
[junit4:junit4] 1> 261428 [TEST-SolrExampleStreamingBinaryTest.testSetNullUpdates-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testSetNullUpdates
[junit4:junit4] 1> 261428 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@555bcf
[junit4:junit4] 1> 261681 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdates (1437437684024868864)]} 0 250
[junit4:junit4] 1> 261682 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: testSetNullUpdates is 200
[junit4:junit4] 1> 261682 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@555bcf
[junit4:junit4] 1> 261685 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 261688 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_11,generation=37}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_12,generation=38}
[junit4:junit4] 1> 261688 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 38
[junit4:junit4] 1> 261689 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@eb782f main
[junit4:junit4] 1> 261690 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@eb782f main{StandardDirectoryReader(segments_12:103:nrt _q(4.4):C12 _r(4.4):C1)}
[junit4:junit4] 1> 261690 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 261690 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 261693 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@eb782f main{StandardDirectoryReader(segments_12:103:nrt _q(4.4):C12 _r(4.4):C1)}
[junit4:junit4] 1> 261694 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 9
[junit4:junit4] 1> 261695 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@c54f39
[junit4:junit4] 1> 261948 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[testSetNullUpdates (1437437684305887232)]} 0 250
[junit4:junit4] 1> 261949 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: testSetNullUpdates is 200
[junit4:junit4] 1> 261950 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@c54f39
[junit4:junit4] 1> 261953 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 261955 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_12,generation=38}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_13,generation=39}
[junit4:junit4] 1> 261956 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – newest commit generation = 39
[junit4:junit4] 1> 261957 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1d10a74 main
[junit4:junit4] 1> 261958 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1d10a74 main{StandardDirectoryReader(segments_13:107:nrt _q(4.4):C12 _s(4.4):C1)}
[junit4:junit4] 1> 261958 [qtp20112216-213] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 261958 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 261960 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1d10a74 main{StandardDirectoryReader(segments_13:107:nrt _q(4.4):C12 _s(4.4):C1)}
[junit4:junit4] 1> 261961 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 8
[junit4:junit4] 1> 261965 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=id:testSetNullUpdates} hits=1 status=0 QTime=1
[junit4:junit4] 1> 261966 [TEST-SolrExampleStreamingBinaryTest.testSetNullUpdates-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testSetNullUpdates
[junit4:junit4] 1> 261973 [TEST-SolrExampleStreamingBinaryTest.testUpdateRequestWithParameters-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testUpdateRequestWithParameters
[junit4:junit4] 1> 261974 [TEST-SolrExampleStreamingBinaryTest.testUpdateRequestWithParameters-seed#[3E85581A9914AB60]] INFO org.apache.solr.client.solrj.impl.HttpClientUtil – Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 1> 262067 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1083b6d realtime
[junit4:junit4] 1> 262067 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437684690714624)} 0 2
[junit4:junit4] 1> 262071 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262071 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_13,generation=39}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_14,generation=40}
[junit4:junit4] 1> 262072 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – newest commit generation = 40
[junit4:junit4] 1> 262072 [qtp20112216-212] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1f8560 main
[junit4:junit4] 1> 262073 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1f8560 main{StandardDirectoryReader(segments_13:109:nrt)}
[junit4:junit4] 1> 262073 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262073 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262075 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1f8560 main{StandardDirectoryReader(segments_13:109:nrt)}
[junit4:junit4] 1> 262076 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 5
[junit4:junit4] 1> 262077 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6ccae7
[junit4:junit4] 1> 262077 [concurrentUpdateScheduler-2-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@693049
[junit4:junit4] 1> 262077 [concurrentUpdateScheduler-2-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@f9b9f0
[junit4:junit4] 1> 262077 [concurrentUpdateScheduler-2-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1584cdf
[junit4:junit4] 1> 262078 [concurrentUpdateScheduler-2-thread-3] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@f9b9f0
[junit4:junit4] 1> 262077 [concurrentUpdateScheduler-2-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1d1d43c
[junit4:junit4] 1> 262079 [concurrentUpdateScheduler-2-thread-2] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1584cdf
[junit4:junit4] 1> 262080 [concurrentUpdateScheduler-2-thread-5] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1d1d43c
[junit4:junit4] 1> 262330 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={overwrite=false&version=2&wt=javabin} {add=[id1 (1437437684705394688)]} 0 250
[junit4:junit4] 1> 262332 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: id1 is 200
[junit4:junit4] 1> 262332 [concurrentUpdateScheduler-2-thread-1] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@6ccae7
[junit4:junit4] 1> 262337 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={overwrite=false&version=2&wt=javabin} {add=[id1 (1437437684712734720)]} 0 250
[junit4:junit4] 1> 262338 [concurrentUpdateScheduler-2-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: id1 is 200
[junit4:junit4] 1> 262338 [concurrentUpdateScheduler-2-thread-4] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@693049
[junit4:junit4] 1> 262341 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262345 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_14,generation=40}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_15,generation=41}
[junit4:junit4] 1> 262345 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – newest commit generation = 41
[junit4:junit4] 1> 262346 [qtp20112216-212] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@6719d6 main
[junit4:junit4] 1> 262347 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@6719d6 main{StandardDirectoryReader(segments_15:113:nrt _t(4.4):C1 _u(4.4):C1)}
[junit4:junit4] 1> 262347 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262347 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262349 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@6719d6 main{StandardDirectoryReader(segments_15:113:nrt _t(4.4):C1 _u(4.4):C1)}
[junit4:junit4] 1> 262350 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 9
[junit4:junit4] 1> 262353 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=2 status=0 QTime=0
[junit4:junit4] 1> 262355 [TEST-SolrExampleStreamingBinaryTest.testUpdateRequestWithParameters-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testUpdateRequestWithParameters
[junit4:junit4] 1> 262362 [TEST-SolrExampleStreamingBinaryTest.testStreamingRequest-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testStreamingRequest
[junit4:junit4] 1> 262366 [qtp20112216-213] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@12f84df realtime
[junit4:junit4] 1> 262367 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437685004238848)} 0 2
[junit4:junit4] 1> 262371 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262371 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_15,generation=41}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_16,generation=42}
[junit4:junit4] 1> 262372 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 42
[junit4:junit4] 1> 262372 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@da6f07 main
[junit4:junit4] 1> 262373 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@da6f07 main{StandardDirectoryReader(segments_15:115:nrt)}
[junit4:junit4] 1> 262373 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262373 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262375 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@da6f07 main{StandardDirectoryReader(segments_15:115:nrt)}
[junit4:junit4] 1> 262376 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 6
[junit4:junit4] 1> 262379 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=0
[junit4:junit4] 1> 262380 [TEST-SolrExampleStreamingBinaryTest.testStreamingRequest-seed#[3E85581A9914AB60]] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – blocking for commit/optimize
[junit4:junit4] 1> 262387 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262389 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_16,generation=42}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_17,generation=43}
[junit4:junit4] 1> 262389 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – newest commit generation = 43
[junit4:junit4] 1> 262391 [qtp20112216-201] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@19fb1b4 main
[junit4:junit4] 1> 262391 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@19fb1b4 main{StandardDirectoryReader(segments_17:117:nrt _v(4.4):C10)}
[junit4:junit4] 1> 262391 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262391 [qtp20112216-201] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262393 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@19fb1b4 main{StandardDirectoryReader(segments_17:117:nrt _v(4.4):C10)}
[junit4:junit4] 1> 262394 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {add=[0 (1437437685023113216), 1 (1437437685024161792), 2 (1437437685024161793), 3 (1437437685024161794), 4 (1437437685025210368), 5 (1437437685025210369), 6 (1437437685025210370), 7 (1437437685026258944), 8 (1437437685026258945), 9 (1437437685026258946)],commit=} 0 11
[junit4:junit4] 1> 262397 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&fl=id,score,_docid_&wt=javabin&q=*:*} hits=10 status=0 QTime=0
[junit4:junit4] 1> 262401 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&fl=id,score,_docid_&wt=javabin&q=*:*} hits=10 status=0 QTime=0
[junit4:junit4] 1> 262402 [TEST-SolrExampleStreamingBinaryTest.testStreamingRequest-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testStreamingRequest
[junit4:junit4] 1> 262410 [TEST-SolrExampleStreamingBinaryTest.testAddRetrieve-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testAddRetrieve
[junit4:junit4] 1> 262414 [qtp20112216-212] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1726b80 realtime
[junit4:junit4] 1> 262415 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {deleteByQuery=*:* (-1437437685054570496)} 0 2
[junit4:junit4] 1> 262416 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@c2efa8
[junit4:junit4] 1> 262668 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id1 (1437437685059813376), id2 (1437437685060861952)]} 0 250
[junit4:junit4] 1> 262669 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: id1 is 200
[junit4:junit4] 1> 262670 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@c2efa8
[junit4:junit4] 1> 262672 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262675 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_17,generation=43}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_18,generation=44}
[junit4:junit4] 1> 262675 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 44
[junit4:junit4] 1> 262677 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@711a87 main
[junit4:junit4] 1> 262678 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@711a87 main{StandardDirectoryReader(segments_18:121:nrt _w(4.4):C2)}
[junit4:junit4] 1> 262678 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262678 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262680 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@711a87 main{StandardDirectoryReader(segments_18:121:nrt _w(4.4):C2)}
[junit4:junit4] 1> 262680 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 8
[junit4:junit4] 1> 262684 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&sort=price+asc&q=*:*} hits=2 status=0 QTime=0
[junit4:junit4] 1> 262686 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1960087
[junit4:junit4] 1> 262938 [qtp20112216-213] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {add=[id1 (1437437685342928896), id2 (1437437685345026048)]} 0 250
[junit4:junit4] 1> 262939 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – Status for: id1 is 200
[junit4:junit4] 1> 262940 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – finished: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1960087
[junit4:junit4] 1> 262942 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 262945 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_18,generation=44}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_19,generation=45}
[junit4:junit4] 1> 262946 [qtp20112216-200] INFO org.apache.solr.core.SolrCore – newest commit generation = 45
[junit4:junit4] 1> 262947 [qtp20112216-200] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1ed72d0 main
[junit4:junit4] 1> 262948 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1ed72d0 main{StandardDirectoryReader(segments_19:125:nrt _x(4.4):C2)}
[junit4:junit4] 1> 262948 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 262948 [qtp20112216-200] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 262950 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1ed72d0 main{StandardDirectoryReader(segments_19:125:nrt _x(4.4):C2)}
[junit4:junit4] 1> 262951 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 9
[junit4:junit4] 1> 262954 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&sort=price+asc&q=*:*} hits=2 status=0 QTime=0
[junit4:junit4] 1> 262959 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&sort=price+asc&q=name:h𩬅llo} hits=1 status=0 QTime=1
[junit4:junit4] 1> 262960 [TEST-SolrExampleStreamingBinaryTest.testAddRetrieve-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAddRetrieve
[junit4:junit4] 1> 262968 [TEST-SolrExampleStreamingBinaryTest.testErrorHandling-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Starting testErrorHandling
[junit4:junit4] 1> 262975 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – Ignoring exception matching ignore_exception
[junit4:junit4] 1> 262975 [qtp20112216-213] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/analysis/field params={analysis.fieldvalue=ignore_exception&version=2&analysis.fieldtype=int&qt=/analysis/field&wt=javabin} status=400 QTime=4
[junit4:junit4] 1> 262982 [qtp20112216-201] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {} 0 1
[junit4:junit4] 1> 262983 [qtp20112216-201] INFO org.apache.solr.core.SolrCore – Ignoring exception matching ignore_exception
[junit4:junit4] 1> 262985 [concurrentUpdateScheduler-1-thread-6] INFO org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer – starting runner: org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrServer$Runner@1fbbb59
[junit4:junit4] 1> 262987 [qtp20112216-200] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {} 0 0
[junit4:junit4] 1>
[...truncated too long message...]
er(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_28,generation=80}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_29,generation=81}
[junit4:junit4] 1> 269370 [qtp20112216-233] INFO org.apache.solr.core.SolrCore – newest commit generation = 81
[junit4:junit4] 1> 269371 [qtp20112216-233] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@1b65c34 main
[junit4:junit4] 1> 269372 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@1b65c34 main{StandardDirectoryReader(segments_29:205:nrt _1p(4.4):C3)}
[junit4:junit4] 1> 269372 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 269372 [qtp20112216-233] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 269375 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@1b65c34 main{StandardDirectoryReader(segments_29:205:nrt _1p(4.4):C3)}
[junit4:junit4] 1> 269376 [qtp20112216-233] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 8
[junit4:junit4] 1> 269379 [qtp20112216-234] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=3 status=0 QTime=1
[junit4:junit4] 1> 269383 [qtp20112216-232] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&wt=javabin} {delete=[0 & 222 (-1437437692362096640), 1 & 222 (-1437437692362096641), 2 & 222 (-1437437692362096642)]} 0 1
[junit4:junit4] 1> 269386 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4] 1> 269387 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – SolrDeletionPolicy.onCommit: commits: num=2
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_29,generation=81}
[junit4:junit4] 1> commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@930ce0 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1b86ff1; maxCacheMB=0.7724609375 maxMergeSizeMB=0.2578125)),segFN=segments_2a,generation=82}
[junit4:junit4] 1> 269387 [qtp20112216-212] INFO org.apache.solr.core.SolrCore – newest commit generation = 82
[junit4:junit4] 1> 269388 [qtp20112216-212] INFO org.apache.solr.search.SolrIndexSearcher – Opening Searcher@137bf6 main
[junit4:junit4] 1> 269388 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener sending requests to Searcher@137bf6 main{StandardDirectoryReader(segments_2a:207:nrt)}
[junit4:junit4] 1> 269388 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – QuerySenderListener done.
[junit4:junit4] 1> 269388 [qtp20112216-212] INFO org.apache.solr.update.UpdateHandler – end_commit_flush
[junit4:junit4] 1> 269391 [searcherExecutor-113-thread-1] INFO org.apache.solr.core.SolrCore – [collection1] Registered new searcher Searcher@137bf6 main{StandardDirectoryReader(segments_2a:207:nrt)}
[junit4:junit4] 1> 269392 [qtp20112216-212] INFO org.apache.solr.update.processor.LogUpdateProcessor – [collection1] webapp=/solr path=/update params={version=2&softCommit=false&waitSearcher=true&wt=javabin&commit=true} {commit=} 0 6
[junit4:junit4] 1> 269395 [qtp20112216-231] INFO org.apache.solr.core.SolrCore – [collection1] webapp=/solr path=/select params={version=2&wt=javabin&q=*:*} hits=0 status=0 QTime=0
[junit4:junit4] 1> 269396 [TEST-SolrExampleStreamingBinaryTest.testAddDelete-seed#[3E85581A9914AB60]] INFO org.apache.solr.SolrTestCaseJ4 – ###Ending testAddDelete
[junit4:junit4] 1> 269616 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.CoreContainer – Shutting down CoreContainer instance=10764013
[junit4:junit4] 1> 269616 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.SolrCore – [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@16f0800
[junit4:junit4] 1> 269632 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.update.UpdateHandler – closing DirectUpdateHandler2{commits=76,autocommit maxTime=15000ms,autocommits=1,soft autocommits=3,optimizes=8,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=322,cumulative_deletesById=5,cumulative_deletesByQuery=36,cumulative_errors=1}
[junit4:junit4] 1> 269632 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.update.SolrCoreState – Closing SolrCoreState
[junit4:junit4] 1> 269633 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.update.DefaultSolrCoreState – SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4] 1> 269633 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.update.DefaultSolrCoreState – closing IndexWriter with IndexWriterCloser
[junit4:junit4] 1> 269634 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.SolrCore – [collection1] Closing main searcher on request.
[junit4:junit4] 1> 269635 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4] 1> 269635 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744 [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744;done=false>>]
[junit4:junit4] 1> 269635 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744
[junit4:junit4] 1> 269636 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – looking to close /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744/index [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744/index;done=false>>]
[junit4:junit4] 1> 269636 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.core.CachingDirectoryFactory – Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/solr/build/solr-solrj/test/J0/solrtest-SolrExampleStreamingBinaryTest-1370847189744/index
[junit4:junit4] 1> 269637 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.eclipse.jetty.server.handler.ContextHandler – stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 1> 269689 [SUITE-SolrExampleStreamingBinaryTest-seed#[3E85581A9914AB60]-worker] INFO org.apache.solr.SolrTestCaseJ4 – ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=Lucene3x, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=fr_BE, timezone=Asia/Irkutsk
[junit4:junit4] 2> NOTE: Linux 3.2.0-45-generic i386/Oracle Corporation 1.8.0-ea (32-bit)/cpus=8,threads=11,free=30543280,total=75497472
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestUpdateRequestCodec, SolrExampleXMLTest, SolrExampleEmbeddedTest, SolrParamTest, TestCoreAdmin, CloudSolrServerTest, ModifiableSolrParamsTest, JettyWebappTest, SolrExampleStreamingBinaryTest]
[junit4:junit4] Completed on J0 in 219.25s, 27 tests, 1 error <<< FAILURES!
[...truncated 19 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:391: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/build.xml:371: 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:437: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:1243: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-4.x-Linux/lucene/common-build.xml:887: There were test failures: 46 suites, 272 tests, 1 error
Total time: 44 minutes 41 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b92 -server -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure